Fedora 35 issues

[vchernin]

I had trouble with Fedora 35, I belive this may be related to this: https://fedoraproject.org/wiki/Changes/WirePlumber
It's the only significant change I know has happened to PipeWire in Fedora 35.

All the below is for 35 but not 34:

On this build the enabling switch in the output section is just grayed out :

This picture is on stable (6.1.0 tag):

This is on this PR's build:

Enabling the switch on this build reliably causes easyeffects to crash within a second.
The only log from doing so is the joyful:
Segmentation fault (core dumped)

I think that issue is likely a regression from some other change in master.

Also I found on Fedora 35 I don't think EasyEffects Flatpak even stable is working at all... I think this is not an EasyEffects problem though.

I am curious what happens with the COPR build on 35.

Originally discussed: #1141 (comment)

[Digitalone1]

So Fedora has wireplumber.

If someone wants to test, follow instructions here.

[Digitalone1]

@vchernin can you try to temporarily disable wireplumber and enable the original pipewire-media-session?

[wwmm]

The app switch is the only thing we do through the session manager. So I agree it is suspicious to have problems after moving to wireplumber.

[vchernin]

I can confirm switching to pipewire-media-session with sudo dnf swap wireplumber pipewire-media-session and reboot fixes the issue and EasyEffects works normally on Fedora 35.

[wwmm]

Well... I imagine that if they are switching to wireplumber is because it is supposed to be a drop-in replacement to the built-in session manager. If that is not happening I think the issue is on the wireplumber side.

[wwmm]

Unless that when wireplumber is running the metadata has a name different than default. That would be a problem as it can be seen at

easyeffects/src/pipe_manager.cpp

Line 968 in e293cf5

if (g_strcmp0(name, "default") == 0) {

. @vchernin run easyeffects in debug mode. At least without wireplumber I see lines like these

pipe_manager: found metadata: settings
pipe_manager: found metadata: default
pipe_manager: found metadata: route-settings

Does the line with the name "default" changes to something else? Our interaction with the session manager is essentially some calls to pw_metadata_set_property. So the metadata object can not be null. But it will be if no metadata named "default" is available. The other types of metadata serve other purposes and are useless for us. So we have to know the right name.

[Digitalone1]

Anyway wireplumber is available on Arch also. If it's not working with it, we have to specify pipewire-media-session as explicit dependency.

[vchernin]

easyeffects.log

[vchernin]

Also I can confirm the issue on Arch, after installing wireplumber and and enabling it per this.

[wwmm]

Curiosity got the best of me and I decided to investigate. It turns out the problem is not where I thought it would be. Removing this line makes things work with WirePlumber

easyeffects/src/effects_base_ui.cpp

Line 672 in e293cf5

holder->info_updated.emit();

. Honestly I do not remember since when this call is done. @Digitalone1 is this a recent change? As the function application_info_update is the callback of the signal emitted by holder->info_updated.emit(); and inside the callback we call pointer_connection_enable->block(); some kind of thread locking may be happening. But I wonder why only when WirePlumber is used... Strange...

[Digitalone1]

Try also adding and removing from the blocklist.

Anyway that call was useful to update the app info UI on switch and blocklist changes. Sometimes it was not updating.

I don't think it's an issue related to pointer_connection_enable because we change the switch only between the connection block/unblock.

easyeffects/src/effects_base_ui.cpp

Line 739 in e293cf5

enable->set_active(is_enabled);

[Digitalone1]

I'm making some tests commenting the info_updated.emit(). If the app is blocklisted while it's not enabled, the enable switch remains sensitive.

Besides, if the app is not enabled from the start (because of Process all Output not enabled), when you add and remove it from the blocklist sometimes the app_info_ui is empty.

[wwmm]

Oh! I see. But maybe there is a better way to achieve the same results. I am not sure we should be calling the connection block function inside the slot that is being handled by the connection we want to block.

I did some tests with Pavucontrol. If we enable and disable the application there the switch changes in our window with no crash. It is only when we change its state by clicking on our switch that the crash happens.

[wwmm]

I did more tests. The real source of the problem is calling

easyeffects/src/effects_base_ui.cpp

Line 739 in e293cf5

enable->set_active(is_enabled);

. The rest is fine. I can see the call to set_active inside the switch callback being a problem. I think that there is a high chance pointer_connection_enable->block(); is not avoiding it because the call happened inside the state changed slot.

[Digitalone1]

The switch changes because pipewire triggers some change and on_app_changed is called to update the ui. Unfortunately this is not happening when the blocklist checkbutton is toggled while the app is disabled.

I'm also testing and find out a bad bug. If show blocklisted apps is untoggled, the app info ui might not work in some situations because the item is not updated. I forgot to update the items of all_players_model inside on_app_changed, I only updated the players_model.

Now I fixed this, but can you suggest me how to update the enable switch on the checkbutton toggle?

For the connection block, all these block/unblock calls are inside application_info_update, so do you mean they have to moved outside?

[wwmm]

For the connection block, all these block/unblock calls are inside application_info_update, so do you mean they have to moved outside?

I do not think we can. We have to do these operations inside the info_updated signal. The real issue is the amount of complexity we are having to add to keep the blocklist interface consistent. Calling enable->set_active inside the info_update signal slot is totally fine as long as we do not do that inside the switch callback. What is totally understandable. It is a miracle the same problem did not happen with the other session manager.

[wwmm]

I've found a way to "fool" gtk XD. Replace the emit call that is inside the switch by

Glib::signal_idle().connect_once([=] { holder->info_updated.emit(); });

This way the emit is not done inside the switch callback. It will be scheduled to run the next time gtk's main loop is idle.

[wwmm]

@Digitalone1 as you are already preparing some changes it may be more productive to add this in your pull request

[wwmm]

This way the emit is not done inside the switch callback. It will be scheduled to run the next time gtk's main loop is idle.

I forgot to say that this fixed the problem with wireplumber. At least on my computer.

[Digitalone1]

If the problem is the holder->info_updated.emit() inside the switch callback, I can delete it since it's not really necessary because when a stream is connected/disconnected pipewire triggers a change and the ui is updated.

We need it when an application is added/removed from the blocklist to update the sensitive property of the enable switch.

Another issue is that on_app_changed is called too many times in a short interval by pipewire. We don't need all those calls, they may trigger race conditions. I wonder if there's a way to skip some calls if another one is already scheduled.

[wwmm]

If the problem is the holder->info_updated.emit() inside the switch callback

Almost that. This signal can be emitted there. The problem was that its slot called enable->set_active. And that call inside its own callback is usually not a good idea. And in this case it was the reason for the crash. Scheduling the info_updated emit for when gtk is idle fixed the problem. But if we do not need to emit this signal there it is better.

[wwmm]

We don't need all those calls, they may trigger race conditions.

I agree that we do not need all these calls. But I am not sure about the race conditions. The signals are scheduled by Glib::signal_idle() for the next moment gtk's main loop is idle. While it is true that there is a high amount of calls gtk batches all of them in a way that increases performance and they are all serialized in gtk's main thread. So there should not be race between threads because everything scheduled by Glib::signal_idle() runs in the main thread.

[wwmm]

Another point is that in service mode almost nothing is done when on_app_changed is called. The overhead is more when the window is opened. At least I have it closed most of the time.

[Digitalone1]

I added a scheduled_update bool flag in NodeInfoHolder and made the following code:

      try {
        item->info = pm->node_map.at(id);

        if (!item->scheduled_update) {
          item->scheduled_update = true;

          Glib::signal_idle().connect_once([=] { item->info_updated.emit(); });

          util::debug("-- update scheduled");
        } else {
          util::debug("-- update skipped");
        }
      } catch (...) {
      }

scheduled_update is made false at the finish of application_info_update callback.

Look at the log

(easyeffects:14616): easyeffects-DEBUG: 17:29:24.573: pipe_manager: new metadata property: 120, target.node, Spa:Id, 90
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.575: -- update scheduled
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.575: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.575: -- app info ui updated
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update scheduled
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.578: -- app info ui updated
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update scheduled
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.579: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: pipe_manager: io.github.celluloid_player.Celluloid port 85 is connected to easyeffects_sink port 80
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: pipe_manager: io.github.celluloid_player.Celluloid port 112 is connected to easyeffects_sink port 59
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.580: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.584: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.584: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.584: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.584: -- app info ui updated
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.636: -- update scheduled
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.637: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.640: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.641: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.641: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.641: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.641: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.641: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.642: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.643: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.644: -- update skipped
(easyeffects:14616): easyeffects-DEBUG: 17:29:24.647: -- app info ui updated

More than 40 calls in less than 100 ms. With this flag we only did 4 updates.

I think this is a good approach. What do you think? Any downside I might miss?

[wwmm]

Any downside I might miss?

So far I did not see one. In PulseEffects the stream info was used to decide if our pipeline should be active or not. But in PipeWire we have the link info for that. So in theory reducing the actions taken when we receive node info updates should not cause problems.

[lakotamm]

I just installed the latest Easyeffects Flatpak 6.1.1 on Fedora 35 Silverblue (19th Sept build). I can confirm that Firefox output is not processed and enabling the button results in a segfault.

104.447362] easyeffects[2126]: segfault at 7ffcc80f9f68 ip 00007f3d718d5741 sp 00007ffcc80f9f10 error 6 in libc-2.31.so[7f3d718a8000+150000]
[ 104.447371] Code: 00 0f 1f 84 00 00 00 00 00 f3 0f 1e fa 55 49 89 f3 48 89 e5 41 57 41 56 41 55 41 54 49 89 fc 48 89 d7 53 48 81 ec 28 01 00 00 <48> 89 b5 08 ff ff ff 0f b6 77 0c 48 89 95 38 ff ff ff 64 48 8b 04
[ 385.899718] easyeffects[6835]: segfault at 7ffd4f741ff8 ip 00007f48cda5c6f8 sp 00007ffd4f742000 error 6 in libc-2.31.so[7f48cda34000+150000]
[ 385.899728] Code: 00 00 48 8b 04 cf 48 f7 f6 48 83 e9 01 73 f3 48 89 d0 c3 0f 1f 80 00 00 00 00 31 d2 eb d6 0f 1f 40 00 f3 0f 1e fa 55 48 89 e5 <41> 57 49 89 f7 41 56 41 55 49 89 d5 41 54 53 4c 89 c3 48 83 ec 58
[ 707.389827] easyeffects[7045]: segfault at 7fff34d41fe8 ip 00007f720f852d82 sp 00007fff34d41ff0 error 6 in libc-2.31.so[7f720f825000+150000]
[ 707.389836] Code: 95 18 ff ff ff e9 74 fa ff ff 66 0f 1f 44 00 00 4c 8d 7d a0 48 8d 8d 5c ff ff ff be 02 00 00 00 48 8d 95 68 ff ff ff 4c 89 ff 19 bc ff ff 89 c1 48 89 85 78 ff ff ff 49 89 c5 c1 e1 06 44 8d

[Digitalone1]

The changes in the last merge request should fix this issue.

[wwmm]

104.447362] easyeffects[2126]: segfault at 7ffcc80f9f68 ip 00007f3d718d5741 sp 00007ffcc80f9f10 error 6 in libc-2.31.so[7f3d718a8000+150000]

@lakotamm the segmentation fault you see has another source that has yet to be found. The crash related to wireplumber that is being discussed here does not happen in functions related to libc. It is better to open a new issue for your case. In the mean time check if you did any update recently and if you rebooted your computer. Sometimes weird problems with no clear source are fixed by rebooting the computer after upgrading the audio server.

[lakotamm]

@lakotamm the segmentation fault you see has another source that has yet to be found. The crash related to wireplumber that is being discussed here does not happen in functions related to libc. It is better to open a new issue for your case. In the mean time check if you did any update recently and if you rebooted your computer. Sometimes weird problems with no clear source are fixed by rebooting the computer after upgrading the audio server.

Thanks for your reply. I will give it another try in the evening. However, the fix made by @Digitalone1 seems to resolve also this issue. Should I do the test again with the official version 6.1.1 and report the issue?

[vchernin]

This debug output is given when wireplumber is entered which fixes/workarounds the problem.

(easyeffects:3): easyeffects-DEBUG: 12:43:29.244: pipe_manager: found metadata: default
(easyeffects:3): easyeffects-DEBUG: 12:43:29.246: pipe_manager: new metadata property: 0, default.configured.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 12:43:29.257: pipe_manager: new metadata property: 0, default.audio.sink, Spa:String:JSON, { "name": "alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink" }
(easyeffects:3): easyeffects-DEBUG: 12:43:29.257: pipe_manager: new metadata property: 0, default.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 12:43:29.257: pipe_manager: new metadata property: 0, default.video.source, Spa:String:JSON, { "name": "v4l2_input.pci-0000_00_14.0-usb-0_8_1.0" }
(easyeffects:3): easyeffects-DEBUG: 12:43:29.257: new default output device: alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink
(easyeffects:3): easyeffects-DEBUG: 12:43:29.299: new default input device: alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source

(easyeffects:3): easyeffects-WARNING **: 12:43:29.301: sie:  link from node 139 to node 140 failed
(easyeffects:3): easyeffects-DEBUG: 12:43:29.402: pipe_manager: new metadata property: 78, target.node, Spa:Id, 127
(easyeffects:3): easyeffects-DEBUG: 12:43:29.404: pipe_manager: Firefox port 80 is connected to easyeffects_sink port 129
(easyeffects:3): easyeffects-DEBUG: 12:43:29.404: pipe_manager: Firefox port 125 is connected to easyeffects_sink port 87
(easyeffects:3): easyeffects-DEBUG: 12:43:29.413: soe: crystalizer latency: 0.000021 s
(easyeffects:3): easyeffects-DEBUG: 12:43:29.413: soe: pipeline latency: 0.020833 ms
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.361: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.507: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.677: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.678: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.678: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:3): easyeffects-DEBUG: 12:43:46.678: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.678: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:3): easyeffects-DEBUG: 12:43:46.679: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 12:43:46.679: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:3): easyeffects-DEBUG: 12:43:46.679: application: input autoloading: the target node name does not match the input device name

[wwmm]

pipe_manager: Firefox port 80 is connected to easyeffects_sink port 129

That is probably the moment when effects are finally turned on. Humm... Wait... What is the full log before running WirePlumber or any other workaround to fix the problem? What is EasyEffects seeing as default output device?

[vchernin]

Full log before entering wireplumber command

(process:35): easyeffects-DEBUG: 12:49:12.194: main: locale directory: /app/share/locale
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system input presets directory: "/app/etc/xdg/easyeffects/input"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system input presets directory: "/etc/easyeffects/input"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system input presets directory: "/etc/xdg/easyeffects/input"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system output presets directory: "/app/etc/xdg/easyeffects/output"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system output presets directory: "/etc/easyeffects/output"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: system output presets directory: "/etc/xdg/easyeffects/output"; 
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: user presets directory already exists: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: user presets directory already exists: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/input
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: user presets directory already exists: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/output
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: user presets directory already exists: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/input
(easyeffects:35): easyeffects-DEBUG: 12:49:12.227: presets_manager: user presets directory already exists: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/output
(easyeffects:35): easyeffects-DEBUG: 12:49:12.243: application: easyeffects version: 6.1.2
(easyeffects:35): easyeffects-DEBUG: 12:49:12.244: pipe_manager: compiled with pipewire: 0.3.31
(easyeffects:35): easyeffects-DEBUG: 12:49:12.244: pipe_manager: linked to pipewire: 0.3.31
(easyeffects:35): easyeffects-DEBUG: 12:49:12.246: pipe_manager: core version: 0.3.37
(easyeffects:35): easyeffects-DEBUG: 12:49:12.246: pipe_manager: core name: pipewire-0
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: found metadata: settings
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: found metadata: default
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Sink 46 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_5__sink was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Sink 47 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_4__sink was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Sink 48 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_3__sink was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Sink 49 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Source 50 alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__source was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Source 51 alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: found metadata: default
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Stream/Output/Audio 78 Firefox was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Firefox port 80 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink port 66
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Firefox port 140 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink port 64
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Firefox port 140 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_5__sink port 52
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Firefox port 80 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_5__sink port 54
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Sink 91 easyeffects_sink was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.248: pipe_manager: Audio/Source/Virtual 79 easyeffects_source was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.249: pipe_manager: new metadata property: 0, default.configured.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:35): easyeffects-DEBUG: 12:49:12.249: pipe_manager: new metadata property: 0, default.video.source, Spa:String:JSON, { "name": "v4l2_input.pci-0000_00_14.0-usb-0_8_1.0" }
(easyeffects:35): easyeffects-DEBUG: 12:49:12.249: pipe_manager: new metadata property: 0, default.audio.sink, Spa:String:JSON, { "name": "alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink" }
(easyeffects:35): easyeffects-DEBUG: 12:49:12.249: pipe_manager: new metadata property: 0, default.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }

(easyeffects:35): easyeffects-WARNING **: 12:49:12.283: lv2_wrapper: Could not find the plugin: http://drobilla.net/plugins/mda/Loudness
(easyeffects:35): easyeffects-DEBUG: 12:49:12.283: soe: http://drobilla.net/plugins/mda/Loudness is not installed

(easyeffects:35): easyeffects-WARNING **: 12:49:12.283: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: loudness

(easyeffects:35): easyeffects-WARNING **: 12:49:12.283: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: output

(easyeffects:35): easyeffects-WARNING **: 12:49:12.283: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: link
(easyeffects:35): easyeffects-DEBUG: 12:49:12.299: lv2_wrapper: http://lsp-plug.in/plugins/lv2/sc_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.331: lv2_wrapper: http://lsp-plug.in/plugins/lv2/comp_delay_x2_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.379: lv2_wrapper: http://lsp-plug.in/plugins/lv2/para_equalizer_x32_lr requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.445: lv2_wrapper: http://lsp-plug.in/plugins/lv2/limiter_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.461: lv2_wrapper: http://lsp-plug.in/plugins/lv2/loud_comp_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.476: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.476: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/options#options
(easyeffects:35): easyeffects-DEBUG: 12:49:12.500: lv2_wrapper: http://lsp-plug.in/plugins/lv2/mb_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.553: pipe_manager: Filter pe_soe_output_level, id = 90, was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.555: soe: output_level successfully connected to pipewire graph
(easyeffects:35): easyeffects-DEBUG: 12:49:12.556: pipe_manager: Filter pe_soe_spectrum, id = 81, was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.558: soe: spectrum successfully connected to pipewire graph
(easyeffects:35): easyeffects-DEBUG: 12:49:12.571: pipe_manager: Filter pe_soe_crystalizer, id = 95, was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.573: soe: crystalizer successfully connected to pipewire graph
(easyeffects:35): easyeffects-DEBUG: 12:49:12.588: soe: output_level: new PipeWire blocksize: 2048

(easyeffects:35): easyeffects-WARNING **: 12:49:12.623: lv2_wrapper: Could not find the plugin: http://drobilla.net/plugins/mda/Loudness
(easyeffects:35): easyeffects-DEBUG: 12:49:12.623: sie: http://drobilla.net/plugins/mda/Loudness is not installed

(easyeffects:35): easyeffects-WARNING **: 12:49:12.623: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: loudness

(easyeffects:35): easyeffects-WARNING **: 12:49:12.623: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: output

(easyeffects:35): easyeffects-WARNING **: 12:49:12.623: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: link
(easyeffects:35): easyeffects-DEBUG: 12:49:12.640: lv2_wrapper: http://lsp-plug.in/plugins/lv2/sc_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.671: lv2_wrapper: http://lsp-plug.in/plugins/lv2/comp_delay_x2_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.720: lv2_wrapper: http://lsp-plug.in/plugins/lv2/para_equalizer_x32_lr requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.786: lv2_wrapper: http://lsp-plug.in/plugins/lv2/limiter_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.802: lv2_wrapper: http://lsp-plug.in/plugins/lv2/loud_comp_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.816: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.816: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/options#options
(easyeffects:35): easyeffects-DEBUG: 12:49:12.841: lv2_wrapper: http://lsp-plug.in/plugins/lv2/mb_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:35): easyeffects-DEBUG: 12:49:12.892: pipe_manager: Filter pe_sie_output_level, id = 110, was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.895: sie: output_level successfully connected to pipewire graph
(easyeffects:35): easyeffects-DEBUG: 12:49:12.896: pipe_manager: Filter pe_sie_spectrum, id = 134, was added
(easyeffects:35): easyeffects-DEBUG: 12:49:12.898: sie: spectrum successfully connected to pipewire graph

(easyeffects:35): easyeffects-WARNING **: 12:49:12.910: sie:  link from node 110 to node 79 failed

(easyeffects:35): easyeffects-WARNING **: 12:49:12.916: sie:  link from node 110 to node 79 failed
easyeffects-INFO: 12:49:12.916: application: disabling global bypass
(easyeffects:35): easyeffects-DEBUG: 12:49:12.934: application_ui: Icon Theme Adwaita detected

(easyeffects:35): easyeffects-WARNING **: 12:49:12.984: sie:  link from node 110 to node 79 failed

(easyeffects:35): easyeffects-WARNING **: 12:49:12.986: sie:  link from node 110 to node 79 failed
(easyeffects:35): easyeffects-DEBUG: 12:49:13.172: portal: background request successfully completed
(easyeffects:35): easyeffects-DEBUG: 12:49:13.172: new default output device: alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink
(easyeffects:35): easyeffects-DEBUG: 12:49:13.189: new default input device: alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source

(easyeffects:35): easyeffects-WARNING **: 12:49:13.193: sie:  link from node 110 to node 79 failed
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: input autoloading: the target node name does not match the input device name
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:35): easyeffects-DEBUG: 12:49:13.197: application: input autoloading: the target node name does not match the input device name
(easyeffects:35): easyeffects-DEBUG: 12:49:13.198: soe: crystalizer blocksize: 2048

After wireplumber command (should be same as above comment)

(easyeffects:35): easyeffects-DEBUG: 12:50:40.196: pipe_manager: found metadata: default
(easyeffects:35): easyeffects-DEBUG: 12:50:40.199: pipe_manager: new metadata property: 0, default.configured.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:35): easyeffects-DEBUG: 12:50:40.210: pipe_manager: new metadata property: 0, default.audio.sink, Spa:String:JSON, { "name": "alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink" }
(easyeffects:35): easyeffects-DEBUG: 12:50:40.210: pipe_manager: new metadata property: 0, default.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:35): easyeffects-DEBUG: 12:50:40.210: new default output device: alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink
(easyeffects:35): easyeffects-DEBUG: 12:50:40.210: pipe_manager: new metadata property: 0, default.video.source, Spa:String:JSON, { "name": "v4l2_input.pci-0000_00_14.0-usb-0_8_1.0" }
(easyeffects:35): easyeffects-DEBUG: 12:50:40.228: new default input device: alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source

(easyeffects:35): easyeffects-WARNING **: 12:50:40.231: sie:  link from node 110 to node 79 failed
(easyeffects:35): easyeffects-DEBUG: 12:50:40.365: pipe_manager: new metadata property: 78, target.node, Spa:Id, 91
(easyeffects:35): easyeffects-DEBUG: 12:50:40.367: pipe_manager: Firefox port 80 is connected to easyeffects_sink port 129
(easyeffects:35): easyeffects-DEBUG: 12:50:40.367: pipe_manager: Firefox port 140 is connected to easyeffects_sink port 93
(easyeffects:35): easyeffects-DEBUG: 12:50:40.397: soe: crystalizer latency: 0.000021 s
(easyeffects:35): easyeffects-DEBUG: 12:50:40.397: soe: pipeline latency: 0.020833 ms
(easyeffects:35): easyeffects-DEBUG: 12:50:50.358: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: input autoloading: the target node name does not match the input device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:35): easyeffects-DEBUG: 12:50:50.359: application: input autoloading: the target node name does not match the input device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.550: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:35): easyeffects-DEBUG: 12:50:50.550: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: output autoloading: the target node name does not match the output device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: input autoloading: the target node name does not match the input device name
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:35): easyeffects-DEBUG: 12:50:50.551: application: input autoloading: the target node name does not match the input device name

[wwmm]

It is a little unusual to have two lines with pipe_manager: found metadata: default in a normal initialization. If you manually enable the switch when it starts disabled things work or there is no effects being applied even in this case?

[vchernin]

If you manually enable the switch when it starts disabled things work or there is no effects being applied even in this case?

Manually enabling the switch for Firefox has never helped.

[wwmm]

I wonder if we are receiving the wrong metadata object. This would explain effects not working even after enabling the switch. At this moment we rely on the metadata name being equal to default

easyeffects/src/pipe_manager.cpp

Line 1008 in 5a99b71

if (g_strcmp0(name, "default") == 0) {

. In your case we are notified two times about metadata with this name. But we only consider the first. If this is the wrong one metadata operations will not work. In my system what I have is

pipe_manager: found metadata: settings
pipe_manager: found metadata: default
pipe_manager: found metadata: route-settings

But if this is really the problem starting wireplumber again should not help because we should still ignore the extra notification about the metadata. Strange...

[wwmm]

we should still ignore the extra notification about the metadata

Actually that is only going to happen if the calls to

easyeffects/src/pipe_manager.cpp

Line 1009 in 5a99b71

pm->metadata = static_cast<pw_metadata*>(pw_registry_bind(pm->registry, id, type, PW_VERSION_METADATA, 0));

do not return a null pointer. While this is null we still try to initialize the object. Could it be that the first two notifications are coming with a null metadata object?

[vchernin]

Is there already a debug option to print contents of the metadata object EasyEffects receives or would that need a quick patch?
Although maybe there's an easier way, my programming knowledge doesn't go far enough yet.

[wwmm]

Is there already a debug option to print contents of the metadata object EasyEffects receives or would that need a quick patch?

With luck I will have time to add more debug messages today.

[wwmm]

@vchernin master branch updated with a warning that should be printed if the metadata object returned by pw_registry_bind is null.

[vchernin]

Initial startup

(process:3): easyeffects-DEBUG: 16:14:58.128: main: locale directory: /app/share/locale
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system input presets directory: "/app/etc/xdg/easyeffects/input"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system input presets directory: "/etc/easyeffects/input"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system input presets directory: "/etc/xdg/easyeffects/input"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system output presets directory: "/app/etc/xdg/easyeffects/output"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system output presets directory: "/etc/easyeffects/output"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: system output presets directory: "/etc/xdg/easyeffects/output"; 
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: user presets directory created: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: user presets directory created: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/input
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: user presets directory created: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/output
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: user presets directory created: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/input
(easyeffects:3): easyeffects-DEBUG: 16:14:58.180: presets_manager: user presets directory created: /var/home/v/.var/app/com.github.wwmm.easyeffects/config/easyeffects/autoload/output
(easyeffects:3): easyeffects-DEBUG: 16:14:58.197: application: easyeffects version: 6.1.2
(easyeffects:3): easyeffects-DEBUG: 16:14:58.197: pipe_manager: compiled with pipewire: 0.3.31
(easyeffects:3): easyeffects-DEBUG: 16:14:58.197: pipe_manager: linked to pipewire: 0.3.31
(easyeffects:3): easyeffects-DEBUG: 16:14:58.200: pipe_manager: core version: 0.3.37
(easyeffects:3): easyeffects-DEBUG: 16:14:58.200: pipe_manager: core name: pipewire-0
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: found metadata: settings
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: found metadata: default
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Sink 46 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_5__sink was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Sink 47 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_4__sink was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Sink 48 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_3__sink was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Sink 49 alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Source 50 alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__source was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Source 51 alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Stream/Output/Audio 77 Firefox was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Firefox port 82 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink port 64
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Firefox port 86 is connected to alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink port 66
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Sink 85 easyeffects_sink was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: Audio/Source/Virtual 79 easyeffects_source was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: new metadata property: 0, default.configured.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: new metadata property: 0, default.video.source, Spa:String:JSON, { "name": "v4l2_input.pci-0000_00_14.0-usb-0_8_1.0" }
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: new metadata property: 0, default.audio.sink, Spa:String:JSON, { "name": "alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink" }
(easyeffects:3): easyeffects-DEBUG: 16:14:58.202: pipe_manager: new metadata property: 0, default.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 16:14:58.203: pipe_manager: easyeffects_source node successfully retrieved with id 79
(easyeffects:3): easyeffects-DEBUG: 16:14:58.203: pipe_manager: easyeffects_sink node successfully retrieved with id 85

(easyeffects:3): easyeffects-WARNING **: 16:14:58.253: lv2_wrapper: Could not find the plugin: http://drobilla.net/plugins/mda/Loudness
(easyeffects:3): easyeffects-DEBUG: 16:14:58.253: soe: http://drobilla.net/plugins/mda/Loudness is not installed

(easyeffects:3): easyeffects-WARNING **: 16:14:58.253: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: loudness

(easyeffects:3): easyeffects-WARNING **: 16:14:58.253: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: output

(easyeffects:3): easyeffects-WARNING **: 16:14:58.253: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: link
(easyeffects:3): easyeffects-DEBUG: 16:14:58.270: lv2_wrapper: http://lsp-plug.in/plugins/lv2/sc_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.303: lv2_wrapper: http://lsp-plug.in/plugins/lv2/comp_delay_x2_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.353: lv2_wrapper: http://lsp-plug.in/plugins/lv2/para_equalizer_x32_lr requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.422: lv2_wrapper: http://lsp-plug.in/plugins/lv2/limiter_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.438: lv2_wrapper: http://lsp-plug.in/plugins/lv2/loud_comp_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.453: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.453: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/options#options
(easyeffects:3): easyeffects-DEBUG: 16:14:58.479: lv2_wrapper: http://lsp-plug.in/plugins/lv2/mb_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.533: pipe_manager: Filter pe_soe_output_level, id = 96, was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.535: soe: output_level successfully connected to pipewire graph
(easyeffects:3): easyeffects-DEBUG: 16:14:58.547: pipe_manager: Filter pe_soe_spectrum, id = 98, was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.549: soe: spectrum successfully connected to pipewire graph
(easyeffects:3): easyeffects-DEBUG: 16:14:58.563: soe: output_level: new PipeWire blocksize: 2048

(easyeffects:3): easyeffects-WARNING **: 16:14:58.580: lv2_wrapper: Could not find the plugin: http://drobilla.net/plugins/mda/Loudness
(easyeffects:3): easyeffects-DEBUG: 16:14:58.580: sie: http://drobilla.net/plugins/mda/Loudness is not installed

(easyeffects:3): easyeffects-WARNING **: 16:14:58.580: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: loudness

(easyeffects:3): easyeffects-WARNING **: 16:14:58.580: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: output

(easyeffects:3): easyeffects-WARNING **: 16:14:58.580: lv2_wrapper: http://drobilla.net/plugins/mda/Loudness port symbol not found: link
(easyeffects:3): easyeffects-DEBUG: 16:14:58.596: lv2_wrapper: http://lsp-plug.in/plugins/lv2/sc_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.627: lv2_wrapper: http://lsp-plug.in/plugins/lv2/comp_delay_x2_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.675: lv2_wrapper: http://lsp-plug.in/plugins/lv2/para_equalizer_x32_lr requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.740: lv2_wrapper: http://lsp-plug.in/plugins/lv2/limiter_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.755: lv2_wrapper: http://lsp-plug.in/plugins/lv2/loud_comp_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.771: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.771: lv2_wrapper: urn:zamaudio:ZaMaximX2 requires feature: http://lv2plug.in/ns/ext/options#options
(easyeffects:3): easyeffects-DEBUG: 16:14:58.795: lv2_wrapper: http://lsp-plug.in/plugins/lv2/mb_compressor_stereo requires feature: http://lv2plug.in/ns/ext/urid#map
(easyeffects:3): easyeffects-DEBUG: 16:14:58.845: pipe_manager: Filter pe_sie_output_level, id = 109, was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.847: sie: output_level successfully connected to pipewire graph
(easyeffects:3): easyeffects-DEBUG: 16:14:58.848: pipe_manager: Filter pe_sie_spectrum, id = 114, was added
(easyeffects:3): easyeffects-DEBUG: 16:14:58.850: sie: spectrum successfully connected to pipewire graph

(easyeffects:3): easyeffects-WARNING **: 16:14:58.866: sie:  link from node 109 to node 79 failed

(easyeffects:3): easyeffects-WARNING **: 16:14:58.882: sie:  link from node 109 to node 79 failed
easyeffects-INFO: 16:14:58.882: application: disabling global bypass
(easyeffects:3): easyeffects-DEBUG: 16:14:58.908: application_ui: Icon Theme Adwaita detected

(easyeffects:3): easyeffects-WARNING **: 16:14:58.942: sie:  link from node 109 to node 79 failed

(easyeffects:3): easyeffects-WARNING **: 16:14:58.944: sie:  link from node 109 to node 79 failed

(easyeffects:3): easyeffects-WARNING **: 16:15:00.458: portal: background request failed:Background request canceled
(easyeffects:3): easyeffects-DEBUG: 16:15:00.459: new default output device: alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink
(easyeffects:3): easyeffects-DEBUG: 16:15:00.474: new default input device: alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source

(easyeffects:3): easyeffects-WARNING **: 16:15:00.477: sie:  link from node 109 to node 79 failed
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] HDMI1
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its output route to: [Out] Headphones
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: output autoloading: the target node name does not match the output device name
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic2
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: input autoloading: the target node name does not match the input device name
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: device alsa_card.pci-0000_00_1f.3-platform-skl_hda_dsp_generic has changed its input route to: [In] Mic1
(easyeffects:3): easyeffects-DEBUG: 16:15:00.480: application: input autoloading: the target node name does not match the input device name

run `wireplumber`

(easyeffects:3): easyeffects-DEBUG: 16:16:01.963: pipe_manager: found metadata: default
(easyeffects:3): easyeffects-DEBUG: 16:16:01.965: pipe_manager: new metadata property: 0, default.configured.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 16:16:01.975: pipe_manager: new metadata property: 0, default.audio.sink, Spa:String:JSON, { "name": "alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink" }
(easyeffects:3): easyeffects-DEBUG: 16:16:01.975: pipe_manager: new metadata property: 0, default.audio.source, Spa:String:JSON, { "name": "alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source" }
(easyeffects:3): easyeffects-DEBUG: 16:16:01.975: pipe_manager: new metadata property: 0, default.video.source, Spa:String:JSON, { "name": "v4l2_input.pci-0000_00_14.0-usb-0_8_1.0" }
(easyeffects:3): easyeffects-DEBUG: 16:16:01.975: new default output device: alsa_output.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp__sink
(easyeffects:3): easyeffects-DEBUG: 16:16:01.990: new default input device: alsa_input.pci-0000_00_1f.3-platform-skl_hda_dsp_generic.HiFi__hw_sofhdadsp_6__source

(easyeffects:3): easyeffects-WARNING **: 16:16:01.993: sie:  link from node 109 to node 79 failed
(easyeffects:3): easyeffects-DEBUG: 16:16:02.120: pipe_manager: new metadata property: 77, target.node, Spa:Id, 85
(easyeffects:3): easyeffects-DEBUG: 16:16:02.120: pipe_manager: Firefox port 82 is connected to easyeffects_sink port 78
(easyeffects:3): easyeffects-DEBUG: 16:16:02.120: pipe_manager: Firefox port 86 is connected to easyeffects_sink port 92

[wwmm]

So the metadata object is not null... Humm... Why the hell the switch is not moving apps to our sink... All it does is asking the session manager to do the move. Is Pavucontrol able to move apps between sinks with no problems?

[vchernin]

Is Pavucontrol able to move apps between sinks with no problems?

Could you provide steps to do this? I am trying different options in Pavucontrol but I am not sure what moves apps between sinks. Is the video below correct?

Kooha-09-29-2021-16-47-08.mp4

I tried (what I thought would) move the app between the sinks.. but clicking the option didn't do anything...

[wwmm]

You did the right procedure. Is pavucontrol also running in a flatpak container? In any case something seems wrong even after starting wireplumber manually. When the app is moved away from our virtual sink the switch in EasyEffects should go to off and as Firefox is the only app shown in our players tab there should be no spectrum activity after that. Things are more broken than expected...

By the way why is even possible to run wireplumber manually? Isn't its service already running? I do not think it is supposed to do something when another instance is already loaded. It is weird it acts like nothing it there.

[wwmm]

When the app is moved away from our virtual sink the switch in EasyEffects should go to off and as Firefox is the only app shown in our players tab there should be no spectrum activity after that.

That when the move to another sink is actually done. What obviously did not happen. If Pavucontrol is not being able to do that the odds that we will be able to fix this problem on EasyEffects are very small. We may need to contact WirePlumber developers.

[vchernin]

Yes Pavucontrol is in Flatpak as well. I'll try the RPM. If nothing is different then yeah it sounds like something relating to WirePlumber itself.

The WirePlumber service is definitely already running, since I can hear audio just fine. It's "just" EasyEffects and Pavucontrol which seem to be behaving strangely so far.

[vchernin]

Running the same test as above except with Pavucontrol RPM behaves as hoped. I am able to change Firefox's audio output to the EasyEffects sink (if that is the correct term). Then everything works, similar to running wireplumber.

So it seems like WirePlumber has an issue with moving Flatpak apps to another sink?

[vchernin]

Yup, back with pipewire-media-session everything is fine. I can enable the firefox switch in easyeffects flatpak, which enables the easyeffects sink for firefox in pavucontrol Flatpak. It updates and makes sense. The same is not true with wireplumber + flatpak.

pipewire-media-session + Flatpak EE and Pavucontrol

Kooha-09-29-2021-20-59-27.mp4

WirePlumber + Flatpak EE and Pavucontrol

Kooha-09-29-2021-21-09-47.mp4

Since this behaviour wasn't found on the RPM versions, there is clearly a wireplumber/flatpak issue with changing sinks (if that is the right term). Who's fault it is I don't know, but I really doubt it's easyeffects' or pavucontrol since it works correctly with non flatpak builds.

[garrett]

So it seems like WirePlumber has an issue with moving Flatpak apps to another sink?

Oh, this would explain the issues I have trying to use EasyEffects on Fedora 35 and also Spotify (which now needs a re-launch when audio output changes).

I couldn't find an issue for this in WirePlumber, so I opened one @ https://gitlab.freedesktop.org/pipewire/wireplumber/-/issues/59

[wwmm]

Now I remember that when I ported PulseEffects to PipeWire we also had problems moving apps in the flatpak install https://gitlab.freedesktop.org/pipewire/pipewire/-/issues/667. At the time Taymans did some changes to how flatpak permissions were handled. On our side we had to set our media category tag to Manager

  pw_properties* props_context = pw_properties_new(nullptr, nullptr);

  pw_properties_set(props_context, PW_KEY_MEDIA_TYPE, "Audio");
  pw_properties_set(props_context, PW_KEY_MEDIA_CATEGORY, "Manager");
  pw_properties_set(props_context, PW_KEY_MEDIA_ROLE, "Music");

  context = pw_context_new(pw_thread_loop_get_loop(thread_loop), props_context, 0);

I wonder if WirePlumber is making use of this. Probably not. I will add this information on the issue opened by @garrett

[cschalle]

Just testing here on my F35 system with Easyeffects installed as an RPM and using the latest Wireplumber test build (https://bodhi.fedoraproject.org/updates/FEDORA-2021-b70755fdc3). Easyeffects seems to work perfectly fine for me.

[vchernin]

@cschalle thanks for testing, indeed there were issues but by now they were solved for the RPM version. The last issue being discussed here was specific to Flatpak, and that's been fixed with the latest WirePlumber release. So EasyEffects should be mostly ready for Fedora 35/WirePlumber.

To update the recap of what was discussed here:

• A WirePlumber-related segfault (fixed in EasyEffects 6.1.2)
• Upstream issue about confusion whether WirePlumber or pipewire-media-session was active.
• Upstream issue about Flatpak apps not getting manager permissions (fixed in WirePlumber 0.4.3).
• Upstream issue about virtual sources with WirePlumber (fixed in WirePlumber 0.4.3).

I did see @Digitalone1 mentioned:

EasyEffects virtual source has issues on wireplumber

Is that still an issue?

[wwmm]

EasyEffects virtual source has issues on wireplumber

It should be fixed too https://gitlab.freedesktop.org/pipewire/wireplumber/-/issues/57

[wwmm]

I did not try to install wireplumber's master branch to test and see if ti is really the case. But it seems it was an easy fix to do on their side.

[vchernin]

Ah right, thanks. I guess this issue can be closed then?

WirePlumber 0.4.3 should make it to Arch's repos soon hopefully.

Oh, it already made it!

[wwmm]

Oh, it already made it!

I installed it now and our virtual source is working with wireplumber. The previous issues seem to be gone.

[lakotamm]

With the new version of wireplumber, everything is working on Silverblue 35 + flatpak easyeffects! Thank you very much guys!