wwmm / easyeffects

Limiter, compressor, convolver, equalizer and auto volume and many other plugins for PipeWire applications
GNU General Public License v3.0
6.4k stars 267 forks source link

Easy Effects breaks VLC / MPV audio #2271

Open Dejv311 opened 1 year ago

Dejv311 commented 1 year ago

EasyEffects Version

7.0.3

What package are you using?

Other (specify below)

Distribution

Downloaded from PopOs store

Describe the bug

Easy effects works fine with chrome. I experienced some bugs with old games such as Unreal tournament 99, but I simply excluded those and avoided issues. But since using Easy Effects I cannot use VLC or SM Player with MPV at all!

For some reason audio from VLC/MPV is very very quiet and tinny. When I start playing VLC/MPV audio and then play chrome (youtube) audio, even the chrome audio becomes quiet and tinny. When I play chrome audio (youtube) and then start VLC/MPV both sound alright!

If I play youtube, then VLC/MPV, then it works -> but if I pause youtube, wait for it to go idle and then pause/unpause VLC/MPV it breaks again.

When audio is broken and I go to ubuntu settings and switch the output device from speakers to easy effects sink and then back to speakers the issue resolves until I play/pause again.

Thinks I have tried: Global bypass, Exluding VLC/MPV, Switching audio outputs in VLC/MPV, restarting computer.

Expected Behavior

Audio goes brrr even in VLC/MPV

Debug Log

Debug Log
``` Paste your log here ```

Additional Information

No response

wwmm commented 1 year ago

If I play youtube, then VLC/MPV, then it works -> but if I pause youtube, wait for it to go idle and then pause/unpause VLC/MPV it breaks again.

That is really strange. In this situation PipeWire is probably switching to a different latency. But this should not cause a big difference in volume level.

Follow step number 3 in this link https://github.com/wwmm/easyeffects/wiki/Reporting-Bugs#basic-logs to get some logs while trying to use VLC and/or MPV. Maybe something useful will be shown But it seems more likely a problem with WirePlumber or PipeWire. The interaction of the audio players with EasyEffects happens through a virtual sink that is just an ordinary PipeWire's null-sink. We do not do special handling for audio players. For EasyEffects they are all just a playback stream.

Dejv311 commented 1 year ago

Hi, thank you for having a look at this. First of all i cannot launch "easyeffects" command in console for some reason (maybe PopOs store limitation?).

abc@xxx:~$ easyeffects -q easyeffects: command not found abc@xxx:~$ G_MESSAGES_DEBUG=easyeffects easyeffects easyeffects: command not found

Secondly I found out, that MPV breaks only with some videos. Is it possible, that the audio format breaks the pipewire? I also found out (the hard way), that Skype desktop application breaks the audio too, while the browser version works fine.

If you want to, I can send you the video, but I assume it would work fine on your machine.

One noteworthy thing about my laptop is that it has multiple speakers. And Ubuntu (PopOs) offers me to choose between stereo and 4.0 surround sound output in the settings. From my experience choosing 4.0 surround makes the audio sound strange (I think it uses specialized speakers (subwoofers/tweeters) as general speaker).

wwmm commented 1 year ago

First of all i cannot launch "easyeffects" command in console for some reason (maybe PopOs store limitation?).

Not a limitation. You probably have EasyEffects through a Flatpak package. In this case run

G_MESSAGES_DEBUG=easyeffects flatpak run com.github.wwmm.easyeffects

Secondly I found out, that MPV breaks only with some videos. Is it possible, that the audio format breaks the pipewire? I also found out (the hard way), that Skype desktop application breaks the audio too, while the browser version works fine.

It is strange that it only happens with some formats. As Audio processing can only be done when raw PCM samples are used it should not matter which video file is being played because the player should decode its audio data to PCM instead of sending the compressed data.

From my experience choosing 4.0 surround makes the audio sound strange (I think it uses specialized speakers (subwoofers/tweeters) as general speaker).

For some reasons EasyEffects is not going to work automatically with surround devices. This comment has a workaround https://github.com/wwmm/easyeffects/issues/1126#issuecomment-1147748628.

Dejv311 commented 1 year ago
You have PipeWire 0.3.69 installed
This is newer or the same as PipeWire 0.3.41 required to run Easy Effects
(process:2): easyeffects-DEBUG: 10:24:31.123:   easyeffects.cpp:35  easyeffects version: 7.0.3
(process:2): easyeffects-DEBUG: 10:24:31.123:   easyeffects.cpp:45  locale directory: /app/share/locale
(easyeffects:2): easyeffects-DEBUG: 10:24:31.130:   presets_manager.cpp:49  system input presets directory: "/app/etc/xdg/easyeffects/input"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.130:   presets_manager.cpp:49  system input presets directory: "/etc/easyeffects/input"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:49  system input presets directory: "/etc/xdg/easyeffects/input"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:52  system output presets directory: "/app/etc/xdg/easyeffects/output"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:52  system output presets directory: "/etc/easyeffects/output"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:52  system output presets directory: "/etc/xdg/easyeffects/output"; 
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:194 user presets directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:194 user presets directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/input
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:194 user presets directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/output
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:194 user presets directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/input
(easyeffects:2): easyeffects-DEBUG: 10:24:31.131:   presets_manager.cpp:194 user presets directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/output
(easyeffects:2): easyeffects-DEBUG: 10:24:31.229:   pipe_manager.cpp:1427   compiled with PipeWire: 0.3.41
(easyeffects:2): easyeffects-DEBUG: 10:24:31.229:   pipe_manager.cpp:1428   linked to PipeWire: 0.3.41
(easyeffects:2): easyeffects-DEBUG: 10:24:31.231:   pipe_manager.cpp:1398   core version: 0.3.69
(easyeffects:2): easyeffects-DEBUG: 10:24:31.231:   pipe_manager.cpp:1399   core name: pipewire-0
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1312   found metadata: settings
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1312   found metadata: default
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1312   found metadata: route-settings
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1167   Audio/Sink 55 alsa_output.pci-0000_07_00.6.analog-stereo with serial 56 has been added
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1167   Audio/Source 56 alsa_input.pci-0000_07_00.6.analog-stereo with serial 57 has been added
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1167   Stream/Output/Audio 62 SMPlayer with serial 68 has been added
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1207   SMPlayer port 63 is connected to alsa_output.pci-0000_07_00.6.analog-stereo port 32
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1207   SMPlayer port 64 is connected to alsa_output.pci-0000_07_00.6.analog-stereo port 38
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1167   Audio/Sink 75 easyeffects_sink with serial 338 has been added
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:1167   Audio/Source/Virtual 76 easyeffects_source with serial 339 has been added
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 0, default.configured.audio.sink, Spa:String:JSON, {"name":"bluez_output.14_3F_A6_22_6B_65.1"}
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 0, default.configured.audio.source, Spa:String:JSON, {"name":"alsa_input.pci-0000_07_00.6.analog-stereo"}
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 0, default.video.source, Spa:String:JSON, {"name":"v4l2_input.pci-0000_08_00.0-usb-0_1_1.0"}
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 0, default.audio.sink, Spa:String:JSON, {"name":"alsa_output.pci-0000_07_00.6.analog-stereo"}
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 0, default.audio.source, Spa:String:JSON, {"name":"alsa_input.pci-0000_07_00.6.analog-stereo"}
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 62, target.node, Spa:Id, 68
(easyeffects:2): easyeffects-DEBUG: 10:24:31.233:   pipe_manager.cpp:900    new metadata property: 62, target.object, Spa:Id, 75
(easyeffects:2): easyeffects-DEBUG: 10:24:31.234:   pipe_manager.cpp:1521   easyeffects_sink node successfully retrieved with id 75 and serial 338
(easyeffects:2): easyeffects-DEBUG: 10:24:31.234:   pipe_manager.cpp:1526   easyeffects_source node successfully retrieved with id 76 and serial 339
(easyeffects:2): easyeffects-DEBUG: 10:24:31.239:   plugin_base.cpp:369 soe: output_level successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.241:   plugin_base.cpp:369 soe: spectrum successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.284:   lv2_wrapper.cpp:98  http://lsp-plug.in/plugins/lv2/para_equalizer_x32_lr requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:2): easyeffects-DEBUG: 10:24:31.519:   lv2_wrapper.cpp:98  http://lsp-plug.in/plugins/lv2/sc_limiter_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:2): easyeffects-DEBUG: 10:24:31.521:   plugin_base.cpp:369 soe: equalizer successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.522:   pipe_manager.cpp:1207   easyeffects_sink port 68 is connected to ee_soe_equalizer port 126
(easyeffects:2): easyeffects-DEBUG: 10:24:31.522:   pipe_manager.cpp:1207   easyeffects_sink port 71 is connected to ee_soe_equalizer port 123
(easyeffects:2): easyeffects-DEBUG: 10:24:31.531:   plugin_base.cpp:369 soe: exciter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.531:   pipe_manager.cpp:1207   ee_soe_equalizer port 95 is connected to ee_soe_exciter port 132
(easyeffects:2): easyeffects-DEBUG: 10:24:31.532:   pipe_manager.cpp:1207   ee_soe_equalizer port 90 is connected to ee_soe_exciter port 66
(easyeffects:2): easyeffects-DEBUG: 10:24:31.534:   plugin_base.cpp:369 soe: autogain successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.535:   pipe_manager.cpp:1207   ee_soe_exciter port 116 is connected to ee_soe_autogain port 131
(easyeffects:2): easyeffects-DEBUG: 10:24:31.535:   pipe_manager.cpp:1207   ee_soe_exciter port 94 is connected to ee_soe_autogain port 112
(easyeffects:2): easyeffects-DEBUG: 10:24:31.538:   plugin_base.cpp:369 soe: convolver successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.538:   pipe_manager.cpp:1207   ee_soe_autogain port 96 is connected to ee_soe_convolver port 106
(easyeffects:2): easyeffects-DEBUG: 10:24:31.538:   pipe_manager.cpp:1207   ee_soe_autogain port 101 is connected to ee_soe_convolver port 97
(easyeffects:2): easyeffects-DEBUG: 10:24:31.540:   plugin_base.cpp:369 soe: bass_loudness successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.540:   pipe_manager.cpp:1207   ee_soe_convolver port 93 is connected to ee_soe_bass_loudness port 114
(easyeffects:2): easyeffects-DEBUG: 10:24:31.540:   pipe_manager.cpp:1207   ee_soe_convolver port 98 is connected to ee_soe_bass_loudness port 104
(easyeffects:2): easyeffects-DEBUG: 10:24:31.542:   plugin_base.cpp:369 soe: limiter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.542:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 130 is connected to ee_soe_limiter port 137
(easyeffects:2): easyeffects-DEBUG: 10:24:31.543:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 136 is connected to ee_soe_limiter port 135
(easyeffects:2): easyeffects-DEBUG: 10:24:31.548:   plugin_base.cpp:369 sie: output_level successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.554:   plugin_base.cpp:369 sie: spectrum successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.569:   limiter.cpp:138 soe: limiter latency: 0.010068027 s
(easyeffects:2): easyeffects-DEBUG: 10:24:31.569:   output_level.cpp:37 soe: output_level: PipeWire blocksize: 2048
(easyeffects:2): easyeffects-DEBUG: 10:24:31.569:   output_level.cpp:38 soe: output_level: PipeWire sampling rate: 88200
(easyeffects:2): easyeffects-DEBUG: 10:24:31.570:   stream_output_effects.cpp:317   disconnecting the autogain filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.573:   pipe_manager.cpp:213     111 ee_soe_autogain has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.573:   stream_output_effects.cpp:317   disconnecting the bass_loudness filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.575:   pipe_manager.cpp:213     107 ee_soe_bass_loudness has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.575:   stream_output_effects.cpp:317   disconnecting the convolver filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.575:   pipe_manager.cpp:213     92 ee_soe_convolver has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.575:   stream_output_effects.cpp:317   disconnecting the equalizer filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.576:   pipe_manager.cpp:213     127 ee_soe_equalizer has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.576:   stream_output_effects.cpp:317   disconnecting the exciter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.576:   pipe_manager.cpp:213     65 ee_soe_exciter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.576:   stream_output_effects.cpp:317   disconnecting the limiter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:31.576:   pipe_manager.cpp:213     124 ee_soe_limiter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:31.579:   plugin_base.cpp:369 soe: equalizer successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.579:   pipe_manager.cpp:1207   easyeffects_sink port 68 is connected to ee_soe_equalizer port 122
(easyeffects:2): easyeffects-DEBUG: 10:24:31.580:   pipe_manager.cpp:1207   easyeffects_sink port 71 is connected to ee_soe_equalizer port 119
(easyeffects:2): easyeffects-DEBUG: 10:24:31.586:   plugin_base.cpp:369 soe: exciter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.586:   pipe_manager.cpp:1207   ee_soe_equalizer port 117 is connected to ee_soe_exciter port 105
(easyeffects:2): easyeffects-DEBUG: 10:24:31.587:   pipe_manager.cpp:1207   ee_soe_equalizer port 124 is connected to ee_soe_exciter port 133
(easyeffects:2): easyeffects-DEBUG: 10:24:31.589:   plugin_base.cpp:369 soe: autogain successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.589:   output_level.cpp:37 sie: output_level: PipeWire blocksize: 1024
(easyeffects:2): easyeffects-DEBUG: 10:24:31.589:   output_level.cpp:38 sie: output_level: PipeWire sampling rate: 48000
(easyeffects:2): easyeffects-DEBUG: 10:24:31.589:   pipe_manager.cpp:1207   ee_soe_exciter port 88 is connected to ee_soe_autogain port 116
(easyeffects:2): easyeffects-DEBUG: 10:24:31.590:   pipe_manager.cpp:1207   ee_soe_exciter port 135 is connected to ee_soe_autogain port 66
(easyeffects:2): easyeffects-DEBUG: 10:24:31.592:   plugin_base.cpp:369 soe: convolver successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.592:   pipe_manager.cpp:1207   ee_soe_autogain port 132 is connected to ee_soe_convolver port 115
(easyeffects:2): easyeffects-DEBUG: 10:24:31.593:   pipe_manager.cpp:1207   ee_soe_autogain port 127 is connected to ee_soe_convolver port 123
(easyeffects:2): easyeffects-DEBUG: 10:24:31.595:   plugin_base.cpp:369 soe: bass_loudness successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.596:   pipe_manager.cpp:1207   ee_soe_convolver port 129 is connected to ee_soe_bass_loudness port 93
(easyeffects:2): easyeffects-DEBUG: 10:24:31.596:   pipe_manager.cpp:1207   ee_soe_convolver port 126 is connected to ee_soe_bass_loudness port 97
(easyeffects:2): easyeffects-DEBUG: 10:24:31.599:   plugin_base.cpp:369 soe: limiter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:31.599:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 106 is connected to ee_soe_limiter port 108
(easyeffects:2): easyeffects-DEBUG: 10:24:31.599:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 107 is connected to ee_soe_limiter port 104
easyeffects-INFO: 10:24:31.604:     application.cpp:50  disabling global bypass
(easyeffects:2): easyeffects-DEBUG: 10:24:31.608:   application_ui.cpp:86   Icon Theme Yaru-dark detected
(easyeffects:2): easyeffects-DEBUG: 10:24:32.907:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:32.907:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:33.048:   convolver_ui.cpp:600    irs directory already exists: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/irs
(easyeffects:2): easyeffects-DEBUG: 10:24:34.307:   stream_output_effects.cpp:317   disconnecting the autogain filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.309:   pipe_manager.cpp:213     94 ee_soe_autogain has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.309:   stream_output_effects.cpp:317   disconnecting the bass_loudness filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.310:   pipe_manager.cpp:213     98 ee_soe_bass_loudness has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.310:   stream_output_effects.cpp:317   disconnecting the convolver filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.310:   pipe_manager.cpp:213     95 ee_soe_convolver has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.310:   stream_output_effects.cpp:317   disconnecting the equalizer filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.312:   pipe_manager.cpp:213     128 ee_soe_equalizer has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.312:   stream_output_effects.cpp:317   disconnecting the exciter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.313:   pipe_manager.cpp:213     100 ee_soe_exciter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.313:   stream_output_effects.cpp:317   disconnecting the limiter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.313:   pipe_manager.cpp:213     130 ee_soe_limiter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.317:   plugin_base.cpp:369 soe: equalizer successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.317:   pipe_manager.cpp:1207   easyeffects_sink port 68 is connected to ee_soe_equalizer port 121
(easyeffects:2): easyeffects-DEBUG: 10:24:34.318:   pipe_manager.cpp:1207   easyeffects_sink port 71 is connected to ee_soe_equalizer port 112
(easyeffects:2): easyeffects-DEBUG: 10:24:34.320:   plugin_base.cpp:369 soe: exciter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.320:   pipe_manager.cpp:1207   ee_soe_equalizer port 110 is connected to ee_soe_exciter port 96
(easyeffects:2): easyeffects-DEBUG: 10:24:34.321:   pipe_manager.cpp:1207   ee_soe_equalizer port 130 is connected to ee_soe_exciter port 114
(easyeffects:2): easyeffects-DEBUG: 10:24:34.326:   plugin_base.cpp:369 soe: autogain successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.327:   pipe_manager.cpp:1207   ee_soe_exciter port 91 is connected to ee_soe_autogain port 88
(easyeffects:2): easyeffects-DEBUG: 10:24:34.327:   pipe_manager.cpp:1207   ee_soe_exciter port 104 is connected to ee_soe_autogain port 133
(easyeffects:2): easyeffects-DEBUG: 10:24:34.329:   plugin_base.cpp:369 soe: convolver successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.330:   pipe_manager.cpp:1207   ee_soe_autogain port 105 is connected to ee_soe_convolver port 137
(easyeffects:2): easyeffects-DEBUG: 10:24:34.330:   pipe_manager.cpp:1207   ee_soe_autogain port 128 is connected to ee_soe_convolver port 119
(easyeffects:2): easyeffects-DEBUG: 10:24:34.333:   plugin_base.cpp:369 soe: bass_loudness successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.333:   pipe_manager.cpp:1207   ee_soe_convolver port 102 is connected to ee_soe_bass_loudness port 129
(easyeffects:2): easyeffects-DEBUG: 10:24:34.334:   pipe_manager.cpp:1207   ee_soe_convolver port 122 is connected to ee_soe_bass_loudness port 123
(easyeffects:2): easyeffects-DEBUG: 10:24:34.336:   plugin_base.cpp:369 soe: limiter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.336:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 115 is connected to ee_soe_limiter port 101
(easyeffects:2): easyeffects-DEBUG: 10:24:34.336:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 98 is connected to ee_soe_limiter port 97
(easyeffects:2): easyeffects-DEBUG: 10:24:34.342:   application.cpp:78  new default output device: alsa_output.pci-0000_07_00.6.analog-stereo
(easyeffects:2): easyeffects-DEBUG: 10:24:34.342:   stream_output_effects.cpp:317   disconnecting the autogain filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.342:   pipe_manager.cpp:213     135 ee_soe_autogain has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   stream_output_effects.cpp:317   disconnecting the bass_loudness filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   pipe_manager.cpp:900    new metadata property: 62, target.node, Spa:Id, 75
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   pipe_manager.cpp:900    new metadata property: 62, target.object, Spa:Id, 338
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   pipe_manager.cpp:213     126 ee_soe_bass_loudness has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   pipe_manager.cpp:1207   SMPlayer port 63 is connected to easyeffects_sink port 74
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   pipe_manager.cpp:1207   SMPlayer port 64 is connected to easyeffects_sink port 73
(easyeffects:2): easyeffects-DEBUG: 10:24:34.343:   stream_output_effects.cpp:317   disconnecting the convolver filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.344:   pipe_manager.cpp:213     117 ee_soe_convolver has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.344:   stream_output_effects.cpp:317   disconnecting the equalizer filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.345:   pipe_manager.cpp:213     131 ee_soe_equalizer has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.345:   stream_output_effects.cpp:317   disconnecting the exciter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.345:   pipe_manager.cpp:213     109 ee_soe_exciter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.345:   stream_output_effects.cpp:317   disconnecting the limiter filter from PipeWire
(easyeffects:2): easyeffects-DEBUG: 10:24:34.346:   pipe_manager.cpp:213     106 ee_soe_limiter has been removed
(easyeffects:2): easyeffects-DEBUG: 10:24:34.349:   plugin_base.cpp:369 soe: equalizer successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.349:   pipe_manager.cpp:1207   easyeffects_sink port 68 is connected to ee_soe_equalizer port 116
(easyeffects:2): easyeffects-DEBUG: 10:24:34.349:   pipe_manager.cpp:1207   easyeffects_sink port 71 is connected to ee_soe_equalizer port 90
(easyeffects:2): easyeffects-DEBUG: 10:24:34.351:   plugin_base.cpp:369 soe: exciter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.351:   pipe_manager.cpp:1207   ee_soe_equalizer port 66 is connected to ee_soe_exciter port 132
(easyeffects:2): easyeffects-DEBUG: 10:24:34.351:   pipe_manager.cpp:1207   ee_soe_equalizer port 106 is connected to ee_soe_exciter port 93
(easyeffects:2): easyeffects-DEBUG: 10:24:34.353:   plugin_base.cpp:369 soe: autogain successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.353:   pipe_manager.cpp:1207   ee_soe_exciter port 134 is connected to ee_soe_autogain port 91
(easyeffects:2): easyeffects-DEBUG: 10:24:34.354:   pipe_manager.cpp:1207   ee_soe_exciter port 97 is connected to ee_soe_autogain port 114
(easyeffects:2): easyeffects-DEBUG: 10:24:34.356:   plugin_base.cpp:369 soe: convolver successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.356:   pipe_manager.cpp:1207   ee_soe_autogain port 96 is connected to ee_soe_convolver port 108
(easyeffects:2): easyeffects-DEBUG: 10:24:34.356:   pipe_manager.cpp:1207   ee_soe_autogain port 131 is connected to ee_soe_convolver port 112
(easyeffects:2): easyeffects-DEBUG: 10:24:34.358:   plugin_base.cpp:369 soe: bass_loudness successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.358:   pipe_manager.cpp:1207   ee_soe_convolver port 99 is connected to ee_soe_bass_loudness port 102
(easyeffects:2): easyeffects-DEBUG: 10:24:34.358:   pipe_manager.cpp:1207   ee_soe_convolver port 121 is connected to ee_soe_bass_loudness port 119
(easyeffects:2): easyeffects-DEBUG: 10:24:34.361:   plugin_base.cpp:369 soe: limiter successfully connected to PipeWire graph
(easyeffects:2): easyeffects-DEBUG: 10:24:34.361:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 137 is connected to ee_soe_limiter port 103
(easyeffects:2): easyeffects-DEBUG: 10:24:34.361:   pipe_manager.cpp:1207   ee_soe_bass_loudness port 92 is connected to ee_soe_limiter port 129
(easyeffects:2): easyeffects-DEBUG: 10:24:34.365:   application.cpp:86  new default input device: alsa_input.pci-0000_07_00.6.analog-stereo
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   application.cpp:98  device alsa_card.pci-0000_07_00.6 has changed its input route to: analog-input-internal-mic
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   application.cpp:130 device alsa_card.pci-0000_07_00.6 has changed its output route to: analog-output-speaker
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   convolver.cpp:282   soe: convolver: irs file: /home/dejv/.var/app/com.github.wwmm.easyeffects/config/easyeffects/irs/Dolby ATMOS ((128K MP3)) 1.Default.irs
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   convolver.cpp:283   soe: convolver: irs rate: 44100 Hz
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   convolver.cpp:284   soe: convolver: irs channels: 2
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   convolver.cpp:285   soe: convolver: irs frames: 10368
(easyeffects:2): easyeffects-DEBUG: 10:24:34.369:   convolver.cpp:308   soe: convolver resampling the kernel to 88200
(easyeffects:2): easyeffects-DEBUG: 10:24:34.376:   convolver.cpp:324   soe: convolver: kernel initialized
(easyeffects:2): easyeffects-DEBUG: 10:24:34.379:   convolver.cpp:442   soe: convolver: zita is ready
(easyeffects:2): easyeffects-DEBUG: 10:24:34.379:   effects_base.cpp:246    soe: pipeline latency: 10.0680275 ms
(easyeffects:2): easyeffects-DEBUG: 10:24:34.410:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.410:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.410:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:34.410:   app_info.cpp:136    cannot lookup application icon SMPlayer in /usr/local/share/pixmaps
(easyeffects:2): easyeffects-DEBUG: 10:24:44.502:   stream_input_effects.cpp:165    No app linked to our device wants to play. Unlinking our filters.

Hi, this is the log. You might be right, that it is a pipewire problem in general. It now happens even if I close easy effects. Only think that helps is switching from "Stereo" to "Surround" and back to "Stereo" in Ubuntu setting. I noticed, that both Skype and MPV use only the small speakers next to my keyboard when broken. The speakers located at the bottom of the laptop are not utilized.

And yes, it still happends only with some videos. I really think it has to do something with the broken surround. It is infact not even a "surround" sound... It is just a bunch of speakers that all serve a different purpose. So I am not sure what would be the outcome of the https://github.com/wwmm/easyeffects/issues/1126#issuecomment-1147748628, since I basically do not want to split the audio into more than 2 channels (I think).

wwmm commented 1 year ago

I noticed, that both Skype and MPV use only the small speakers next to my keyboard when broken. The speakers located at the bottom of the laptop are not utilized.

Switching between using and not using speakers is not normal indeed. When EasyEffects is running it would be understandable having sound in only two channels because it is a stereo application. The way EasyEffects has to do the links is not supported by PipeWire's upmixing/downmixing code. But without EasyEffects in the way the automatic upmixing/downmixing should be working when VLC/MPV play a stereo audio.

Something you may try is looking at PipeWire's logs instead of EasyEffects logs PIPEWIRE_DEBUG=3 flatpak run com.github.wwmm.easyeffects. You can try numbers higher and lower than 3 if there is too many or to few log lines.

Dejv311 commented 1 year ago

The output with MPV playing on the background is:

[I][00360.830698] pw.context   | [      pipewire.c:  668 pw_init()] version 0.3.41
[I][00360.831235] pw.conf      | [          conf.c:  327 conf_load()] 0x555718374cf0: loading config '/app/share/pipewire/client.conf'
[I][00360.831339] pw.context   | [       context.c:  277 pw_context_new()] 0x555718374360: parsed context.properties section
[I][00360.837458] pw.context   | [       context.c:  376 pw_context_new()] 0x555718374360: parsed 2 context.spa-libs items
[I][00360.838094] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-protocol-native
[I][00360.838632] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-client-node
[I][00360.838980] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-client-device
[I][00360.839427] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-adapter
[I][00360.839688] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-metadata
[I][00360.840244] pw.conf      | [          conf.c:  420 load_module()] 0x555718374360: loaded module libpipewire-module-session-manager
[I][00360.840250] pw.context   | [       context.c:  380 pw_context_new()] 0x555718374360: parsed 6 context.modules items
[I][00360.840253] pw.context   | [       context.c:  385 pw_context_new()] 0x555718374360: parsed 0 context.objects items
[I][00360.840256] pw.context   | [       context.c:  388 pw_context_new()] 0x555718374360: parsed 0 context.exec items
[I][00360.840297] pw.context   | [       context.c:  136 context_set_freewheel()] 0x555718374360: exit freewheel
[I][00360.840494] pw.context   | [       context.c:  140 context_set_freewheel()] 0x555718374360: freewheel error:Operation not supported
[I][00360.840502] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:log.level type: value:0
[I][00360.840506] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.rate type: value:48000
[I][00360.840509] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][00360.840512] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.quantum type: value:1024
[I][00360.840515] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.min-quantum type: value:32
[I][00360.840518] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.max-quantum type: value:8192
[I][00360.840521] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.force-quantum type: value:0
[I][00360.840523] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x555718390b70: add id:0 key:clock.force-rate type: value:0
[I][00360.840564] mod.protocol-native | [  local-socket.c:   91 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
You have PipeWire 0.3.69 installed
This is newer or the same as PipeWire 0.3.41 required to run Easy Effects
[I][00361.049077] pw.context   | [      pipewire.c:  668 pw_init()] version 0.3.41
[I][00361.049233] pw.conf      | [          conf.c:  327 conf_load()] 0x55e883df5980: loading config '/app/share/pipewire/client-rt.conf'
[I][00361.049505] pw.context   | [       context.c:  277 pw_context_new()] 0x55e883e68a90: parsed context.properties section
[I][00361.049822] pw.context   | [       context.c:  376 pw_context_new()] 0x55e883e68a90: parsed 2 context.spa-libs items
[E][00361.050313] mod.rtkit    | [  module-rtkit.c:  175 pw_rtkit_bus_get_system()] Failed to connect to system bus: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory
[W][00361.050320] mod.rtkit    | [  module-rtkit.c:  697 pipewire__module_init()] could not get system bus: Connection refused
[I][00361.050358] pw.conf      | [          conf.c:  416 load_module()] 0x55e883e68a90: could not load optional module "libpipewire-module-rtkit": Connection refused
[I][00361.050554] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-protocol-native
[I][00361.050734] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-client-node
[I][00361.050851] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-client-device
[I][00361.050980] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-adapter
[I][00361.051092] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-metadata
[I][00361.051234] pw.conf      | [          conf.c:  420 load_module()] 0x55e883e68a90: loaded module libpipewire-module-session-manager
[I][00361.051239] pw.context   | [       context.c:  380 pw_context_new()] 0x55e883e68a90: parsed 7 context.modules items
[I][00361.051241] pw.context   | [       context.c:  385 pw_context_new()] 0x55e883e68a90: parsed 0 context.objects items
[I][00361.051243] pw.context   | [       context.c:  388 pw_context_new()] 0x55e883e68a90: parsed 0 context.exec items
[I][00361.051282] pw.context   | [       context.c:  136 context_set_freewheel()] 0x55e883e68a90: exit freewheel
[I][00361.051287] pw.context   | [       context.c:  140 context_set_freewheel()] 0x55e883e68a90: freewheel error:Operation not supported
[I][00361.051291] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:log.level type: value:0
[I][00361.051298] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.rate type: value:48000
[I][00361.051301] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][00361.051303] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.quantum type: value:1024
[I][00361.051305] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.min-quantum type: value:32
[I][00361.051308] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.max-quantum type: value:8192
[I][00361.051310] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.force-quantum type: value:0
[I][00361.051312] pw.metadata  | [ impl-metadata.c:  205 impl_set_property()] 0x55e883e21dc0: add id:0 key:clock.force-rate type: value:0
[I][00361.051336] mod.protocol-native | [  local-socket.c:   91 try_connect()] connecting to 'pipewire-0' runtime_dir:/run/user/1000
[I][00361.352328] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) creating -> running
[I][00361.363783] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) creating -> running
[I][00361.366147] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) creating -> running
[I][00361.368496] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) creating -> running
[I][00361.370947] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) creating -> running
[I][00361.373905] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) creating -> running
[I][00361.374625] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.392113] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) creating -> running
[I][00361.392210] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.392273] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.393144] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.393199] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.393242] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.397659] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.397740] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.397783] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.397827] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.397924] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) creating -> running
[I][00361.398256] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.398358] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.398401] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.398476] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.431195] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.431277] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.431326] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.431365] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.431393] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.431408] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.431747] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.431780] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.431820] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.431855] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.431882] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.431917] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.431945] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.431973] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432246] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.432272] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.432293] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432309] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432325] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432340] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432355] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432369] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432558] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432587] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432605] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432620] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432635] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432650] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432823] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432853] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.432871] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432886] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.432901] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.432916] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.438664] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) running -> suspended
[I][00361.438765] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_autogain-0) destroy
[I][00361.438790] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.439582] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.439653] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.439815] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) running -> suspended
[I][00361.439879] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_bass_loudness-0) destroy
[I][00361.439895] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.439931] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.439965] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.439989] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.440010] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.440420] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.440471] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.440536] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) running -> idle
[I][00361.440578] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) idle -> suspended
[I][00361.440620] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_convolver-0) destroy
[I][00361.440636] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.440886] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) running -> suspended
[I][00361.441001] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_equalizer-0) destroy
[I][00361.441051] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.441691] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) running -> idle
[I][00361.441761] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) idle -> suspended
[I][00361.441863] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_exciter-0) destroy
[I][00361.441915] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.442236] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) running -> suspended
[I][00361.442308] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_limiter-0) destroy
[I][00361.442332] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00361.443108] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.443309] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.443385] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) running -> idle
[I][00361.443462] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.443635] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.443713] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) running -> idle
[I][00361.447313] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) creating -> running
[I][00361.450931] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) creating -> running
[I][00361.451688] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.452292] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.452329] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.452358] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.453192] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.453217] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.453350] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) creating -> running
[I][00361.454732] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.455237] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.455267] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.455292] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.456011] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.456037] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.456053] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.456072] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.456194] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) creating -> running
[I][00361.456394] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.456414] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.457651] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.457923] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.457945] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.457967] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.458454] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.458479] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.458496] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.458511] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.458631] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) creating -> running
[I][00361.458932] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.458955] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00361.458973] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.458996] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.459296] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.459329] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.460798] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.460992] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461023] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461050] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-0.000000 0-0 0-0
[I][00361.461371] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461394] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461410] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.461426] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.461479] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) creating -> running
[I][00361.461616] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461637] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.461654] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.461669] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.462048] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.462072] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.462090] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.462105] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.462124] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.462327] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.462349] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.462367] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.462382] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.462398] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.462418] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.462438] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.463004] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.463028] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 10125000-10125000
[I][00361.463045] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.463070] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-1.000000 0-0 0-0
[I][00361.463095] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.463112] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.463150] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) idle -> running
[I][00361.463165] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) idle -> running
[I][00361.463241] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463260] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463282] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.463323] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 1.000000-1.000000 0-0 0-0
[I][00361.463434] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463453] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463470] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463486] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463559] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463577] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463593] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463608] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463679] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463696] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463713] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463727] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463797] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463813] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10125000-10125000
[I][00361.463829] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463844] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463914] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00361.463929] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.272996] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) running -> suspended
[I][00364.273095] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_autogain-0) destroy
[I][00364.273126] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.273950] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.274046] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.274197] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) running -> suspended
[I][00364.274280] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_bass_loudness-0) destroy
[I][00364.274309] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.274363] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.274399] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.274418] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.274433] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.275022] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.275095] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.275174] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) running -> idle
[I][00364.275258] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) idle -> suspended
[I][00364.275358] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_convolver-0) destroy
[I][00364.275409] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.275781] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) running -> suspended
[I][00364.275898] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_equalizer-0) destroy
[I][00364.275921] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.276771] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) running -> idle
[I][00364.276924] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) idle -> suspended
[I][00364.277001] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_exciter-0) destroy
[I][00364.277025] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.277345] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) running -> suspended
[I][00364.277422] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_limiter-0) destroy
[I][00364.277452] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.278099] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.278256] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.278302] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.278365] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.278682] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.278860] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) running -> idle
[I][00364.278871] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) running -> idle
[I][00364.278941] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.282504] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) creating -> running
[I][00364.288075] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) creating -> running
[I][00364.289508] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.289997] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.290060] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.290107] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.290694] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.290753] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.291022] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) creating -> running
[I][00364.292641] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.293456] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.293551] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.293616] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.294469] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.294581] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.294628] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.294670] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.294953] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) creating -> running
[I][00364.295462] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.295546] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.296902] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.297570] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.297623] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.297668] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.298507] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.298566] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.298600] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.298633] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.298899] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) creating -> running
[I][00364.299239] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.299291] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10125000
[I][00364.299319] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.299350] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.299823] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.299866] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10125000-10125000
[I][00364.301826] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.302073] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.302109] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.302144] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.302618] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.302682] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.302712] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.302737] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.302772] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.302811] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.302918] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) creating -> running
[I][00364.303076] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.303135] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.303179] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.303213] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.303248] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.303282] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.303699] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.303746] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.303781] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.303810] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.303840] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.303869] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.303905] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.304291] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) idle -> running
[I][00364.304312] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) idle -> running
[I][00364.304419] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.304459] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10068027-10125000
[I][00364.304489] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.304514] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.304541] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.304567] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.304594] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.304626] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.304660] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.304884] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.304924] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.304952] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.304997] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.305042] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.305085] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.305252] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.305289] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.305322] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.305368] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.305484] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) running -> suspended
[I][00364.305559] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_autogain-0) destroy
[I][00364.305583] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.305655] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.305697] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.305726] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.305751] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.306235] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.306297] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.306361] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.306396] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.306506] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) running -> suspended
[I][00364.306570] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_bass_loudness-0) destroy
[I][00364.306589] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.306641] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.306685] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.306711] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.306735] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.307213] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.307279] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.307367] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) running -> idle
[I][00364.307412] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) idle -> suspended
[I][00364.307460] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_convolver-0) destroy
[I][00364.307481] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.307729] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) running -> suspended
[I][00364.307835] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_equalizer-0) destroy
[I][00364.307859] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.308414] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) running -> idle
[I][00364.308472] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) idle -> suspended
[I][00364.308527] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_exciter-0) destroy
[I][00364.308550] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.308894] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) running -> suspended
[I][00364.308955] pw.node      | [     impl-node.c: 1764 pw_impl_node_destroy()] (ee_soe_limiter-0) destroy
[I][00364.308976] pw.context   | [       context.c: 1060 pw_context_recalc_graph()] 0x55e883e68a90: busy:0 reason:active node destroy
[I][00364.309722] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.309870] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.309911] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.310189] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.310479] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.310679] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) running -> idle
[I][00364.310692] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) running -> idle
[I][00364.310763] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.315017] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_equalizer-0) creating -> running
[I][00364.317456] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_exciter-0) creating -> running
[I][00364.318333] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.318739] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.318807] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.318866] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.319993] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.320045] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.320349] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_autogain-0) creating -> running
[I][00364.321775] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.322118] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.322166] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.322203] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.322696] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.322733] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-10068027
[I][00364.322755] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.322774] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.322911] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_convolver-0) creating -> running
[I][00364.323131] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.323168] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.324498] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.324955] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.325016] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.325083] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.325697] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.325740] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.325766] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.325790] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.325981] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_bass_loudness-0) creating -> running
[I][00364.326547] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.326617] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 0-10068027
[I][00364.326660] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.326695] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.327375] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.327430] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.329835] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.330102] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330136] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330166] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-0.000000 0-0 0-0
[I][00364.330344] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330379] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330402] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.330422] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.330469] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_limiter-0) creating -> running
[I][00364.330603] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330636] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330659] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.330678] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.330953] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.330985] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.331008] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331026] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331174] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_spectrum-0) idle -> running
[I][00364.331193] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_soe_output_level-0) idle -> running
[I][00364.331286] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.331325] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.331351] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331376] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331404] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3de60: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.331888] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331924] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-0.000000 0-0 10068027-10068027
[I][00364.331950] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.331981] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.332011] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f3ea40: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.332247] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.332299] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 0.000000-1.000000 0-0 0-0
[I][00364.332335] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45050: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.332360] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f45c30: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.332543] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332574] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332596] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8846000b0: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.332627] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884600c90: set input latency 1.000000-1.000000 0-0 0-0
[I][00364.332766] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332793] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332815] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884499be0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.332834] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88449ab30: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.332918] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332941] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.332960] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884490e90: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.332977] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884491de0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333060] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.333100] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.333122] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884488670: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333139] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884489250: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333218] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.333239] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 0.000000-1.000000 0-0 10068027-10068027
[I][00364.333259] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e8843195b0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333276] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88431e930: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333354] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f46810: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333371] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e883f473f0: set input latency 1.000000-1.000000 0-0 10068027-10068027
[I][00364.333905] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884959b50: set output latency 1.000000-1.000000 32-32 0-0
[I][00364.334092] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88495ab10: set output latency 1.000000-1.000000 32-32 0-0
[I][00364.334397] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884951670: set output latency 1.000000-1.000000 32-32 0-0
[I][00364.334668] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884952540: set output latency 1.000000-1.000000 32-32 0-0
[I][00364.335474] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_sie_spectrum-0) creating -> running
[I][00364.347220] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_sie_output_level-0) creating -> running
[I][00375.000382] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884959b50: set output latency 0.000000-0.000000 0-0 0-0
[I][00375.001413] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884951670: set output latency 0.000000-1.000000 0-32 0-0
[I][00375.001520] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884952540: set output latency 0.000000-1.000000 0-32 0-0
[I][00375.001599] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e88495ab10: set output latency 0.000000-0.000000 0-0 0-0
[I][00375.001938] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884951670: set output latency 0.000000-0.000000 0-0 0-0
[I][00375.001977] pw.filter    | [        filter.c:  809 handle_latency()] port 0x55e884952540: set output latency 0.000000-0.000000 0-0 0-0
[I][00375.002253] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_sie_spectrum-0) running -> idle
[I][00375.002554] pw.node      | [     impl-node.c:  377 node_update_state()] (ee_sie_output_level-0) running -> idle
wwmm commented 1 year ago

Failed to connect to system bus: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory

This is unexpected. For example I have this file here on my computer. Maybe this is normal when using the Flatpak package. @vchernin will know more about it. In any case I would expect this error to just be related to realtime priorities not being applied. I am not sure if it should be the cause of the problem you are facing.

The rest of the log seems fine. Did the bug happened while this log was being printed?

vchernin commented 1 year ago

This is unexpected. For example I have this file here on my computer.

Flatpak packages with their default permissions can only talk with dbus on their default name i.e. com.github.wwmm.easyeffects. It seems pipewire wants to access the whole socket, which seems like a bug or an implementation that was not done with flatpak in mind.

With pulseeffects the rtkit implementation never worked in flatpak because there was not a reasonable way to give pulseeffects access to rtkit. With pipewire and easyeffects I figured pipewire just took care of it for easyeffects so long as easyeffects could talk to pipewire through the pipewire socket. This log message could mean that rtkit with pipewire is in fact not working in the flatpak build.

I would be interested to see the log when giving access to the system bus with:

PIPEWIRE_DEBUG=3 flatpak run --socket=system-bus --log-system-bus com.github.wwmm.easyeffects

However I don’t think failing to access rtkit would cause the bug that the wrong speakers are being used, rtkit probably needs a new issue.

wwmm commented 1 year ago

This log message could mean that rtkit with pipewire is in fact not working in the flatpak build.

Here on my system htop shows that one of EasyEffects threads has the value -21 in the PRI(priority) column. The threads with normal priority have the value set to 20.

vchernin commented 1 year ago

Here on my system htop shows that one of EasyEffects threads has the value -21 in the PRI(priority) column. The threads with normal priority have the value set to 20.

For me the behaviour does not change with allowing the system bus. Most EasyEffects threads have PRI 20, but I see some have PRI 39 with and without system bus access. I didn't see any other numbers for PRI. I see the same behaviour with the latest fedora build, but in toolbox container.

The only difference with passing --socket=system-bus is the Failed to connect to system bus error goes away, but it seems unimpactful. So it seems like nothing is getting realtime priority on my system (for which the PRI should be negative).

wwmm commented 1 year ago

So it seems like nothing is getting realtime priority on my system (for which the PRI should be negative).

Yes. This is sad... As PipeWire is handling the plugin thread priority I did not expect this to be failing inside Flatpak.

vchernin commented 1 year ago

Yes. This is sad... As PipeWire is handling the plugin thread priority I did not expect this to be failing inside Flatpak.

So I found bumping the pipewire library bundled with flatpak to 0.3.58 or newer fixes this, the PRI column values starts behaving as you described. This seems fine, although while debugging that I found an unrelated pipewire issue.

Before Pipewire commit https://github.com/PipeWire/pipewire/commit/7336f380a5ebf7f449d6e2317e3cbf67633c4c7d (first released in 0.3.44), you see a bunch of these

(easyeffects:2): easyeffects-WARNING **: 23:06:28.702:  pipe_manager.cpp:1252   An error occurred while retrieving the object serial. This module cannot be handled by Easy Effects.

Which is potentially an issue as pipewire 0.3.41 (when used as the daemon) is considered the earliest supported version by the wrapper script. However the application seems to work at least somewhat ok, do you think the flatpak wrapper script should require the pipewire daemon to be 0.3.44 or newer? I hope I can eventually get to https://github.com/wwmm/easyeffects/issues/1677 as it is annoying to keep finding these issues.

Even more unrelated to this issue, I see this message only when using the wrapper script with flatpak, but it seems to have no serious impact as one easyeffects thread still gets PRI -21.

[W][42496.733486] default      | [        thread.c:  101 impl_acquire_rt()] acquire_rt thread:0x7efe6d099640 prio:-1 not implemented
wwmm commented 1 year ago

do you think the flatpak wrapper script should require the pipewire daemon to be 0.3.44 or newer?

In this particular case EE kept working just because we only list the system modules for the user to see. But in other locations when dealing with other kind of objects we expect the serial property to exist. So it is a good idea to force a newer PipeWire release.

I see this message only when using the wrapper script with flatpak, but it seems to have no serious impact as one easyeffects thread still gets PRI -21.

I did not notice this warning here on my computer yet.