emersion/xdg-desktop-portal-wlr

pixman: not supported as a renderer with screencast_version >= 3

rayrapetyan opened this issue · 3 comments

Trying to run https://gitlab.gnome.org/-/snippets/39 inside a Docker container running on a headless host. I'm running these commands at the entrypoint:

labwc -d &
sleep 1
pipewire &
sleep 1
pipewire-pulse &
sleep 1
wireplumber &
sleep 1
/usr/libexec/xdg-desktop-portal-wlr --replace --loglevel DEBUG &
sleep 1
/usr/libexec/xdg-desktop-portal --replace --verbose &
sleep 1
firefox &

Here is startup log:

00:00:00.000 [../src/main.c:139] using config dir ()

00:00:00.000 [../src/config/rcxml.c:1418] cannot read (/rc.xml)
00:00:00.000 [../src/config/rcxml.c:1237] load default key bindings
00:00:00.000 [../src/config/rcxml.c:1242] load default mouse bindings
00:00:00.000 [../src/config/rcxml.c:1137] Loaded 32 merged mousebinds
00:00:00.000 [../src/config/rcxml.c:1284] load default window switcher fields
00:00:00.000 [../src/main.c:153] LABWC_PID=20
00:00:00.000 [backend/backend.c:297] Loading user-specified backends due to WLR_BACKENDS: headless
00:00:00.000 [backend/headless/backend.c:68] Creating headless backend
00:00:00.000 [render/wlr_renderer.c:363] Opening DRM render node '/dev/dri/renderD128'
00:00:00.000 [util/env.c:25] Loading WLR_RENDERER option: pixman
00:00:00.000 [render/pixman/renderer.c:515] Creating pixman renderer
00:00:00.000 [render/allocator/allocator.c:121] Trying to create shm allocator
00:00:00.000 [render/allocator/shm.c:118] Created shm allocator
00:00:00.007 [xcursor/wlr_xcursor.c:243] Loaded cursor theme 'default' at size 24 (123 available cursors)
00:00:00.009 [../src/config/keybind.c:113] Found layout English (US)
00:00:00.009 [types/wlr_idle.c:246] idle manager created
00:00:00.009 [types/wlr_drm_lease_v1.c:715] No DRM backend supplied, failed to create wlr_drm_lease_v1_manager
00:00:00.009 [../src/server.c:417] Failed to create wlr_drm_lease_device_v1
00:00:00.009 [../src/server.c:418] VR will not be available
00:00:00.009 [backend/headless/backend.c:16] Starting headless backend
00:00:00.009 [../src/output.c:121] enable output
00:00:00.009 [../src/output.c:130] set preferred mode
00:00:00.009 [types/output/render.c:229] Attaching empty buffer to output for modeset
00:00:00.009 [types/output/render.c:75] Choosing primary buffer format XR24 (0x34325258) for output 'HEADLESS-1'
00:00:00.009 [render/swapchain.c:105] Allocating new swapchain buffer
00:00:00.010 [render/pixman/renderer.c:120] Created pixman buffer 1280x720
00:00:00.011 [types/output/cursor.c:432] Falling back to software cursor on output 'HEADLESS-1'
00:00:00.011 [../src/server.c:459] WAYLAND_DISPLAY=wayland-0
00:00:00.011 [../src/theme.c:524] no theme override '/themerc-override'
00:00:00.015 [../src/menu/menu.c:561] cannot read /menu.xml
00:00:00.017 [../src/config/session.c:89] Updating dbus execution environment
Environment variable $DISPLAY not set, ignoring.
Failed to import environment: Process org.freedesktop.systemd1 exited with status 1
[I][27684.765139] pw.context   | [      pipewire.c:  647 pw_init()] version 0.3.84
[I][27684.765599] pw.conf      | [          conf.c:  404 conf_load()] 0x5636b8790d20: loaded config '/usr/share/pipewire/pipewire.conf' with 5 items
[I][27684.765651] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire.conf' section 'context.properties'
[I][27684.765711] pw.context   | [       context.c:  245 pw_context_new()] 0x5636b8790430: parsed 7 context.properties items
[I][27684.768141] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire.conf' section 'context.spa-libs'
[I][27684.768166] pw.context   | [       context.c:  346 pw_context_new()] 0x5636b8790430: parsed 9 context.spa-libs items
[I][27684.768183] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire.conf' section 'context.modules'
[I][27684.768188] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-rt args:{
            nice.level    = -11
            #rt.prio      = 88
            #rt.time.soft = -1
            #rt.time.hard = -1
        }
[I][27684.768241] mod.rt       | [     module-rt.c:  564 check_realtime_privileges()] Clamp rtprio 88 to 0
[I][27684.768244] mod.rt       | [     module-rt.c:  572 check_realtime_privileges()] Priority max (0) must be at least 11
[I][27684.768503] mod.rt       | [     module-rt.c:  282 pw_rtkit_check_xdg_portal()] Can't find org.freedesktop.portal.Desktop. Is xdg-desktop-portal running?
[I][27684.768506] mod.rt       | [     module-rt.c:  950 rtkit_get_bus()] found session bus but no portal, trying RTKit fallback
[E][27684.768528] mod.rt       | [     module-rt.c:  262 pw_rtkit_bus_get()] Failed to connect to system bus: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory
[W][27684.768531] mod.rt       | [     module-rt.c:  968 rtkit_get_bus()] Realtime scheduling disabled: unsufficient realtime privileges, Portal not found on session bus, and no system bus for RTKit: Connection refused
[I][27684.768559] pw.conf      | [          conf.c:  577 load_module()] 0x5636b8790430: could not load optional module "libpipewire-module-rt": Connection refused
[I][27684.768562] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-protocol-native args:{
            # List of server Unix sockets, and optionally permissions
            #sockets = [ { name = "pipewire-0" }, { name = "pipewire-0-manager" } ]
        }
[I][27684.768817] mod.protocol-native | [module-protocol-: 1432 add_server()] 0x5636b87aeaa0: Listening on 'pipewire-0'
[I][27684.768847] mod.protocol-native | [module-protocol-: 1432 add_server()] 0x5636b87aeaa0: Listening on 'pipewire-0-manager'
[I][27684.768852] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-protocol-native
[I][27684.768855] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-profiler args:(null)
[I][27684.768904] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-profiler
[I][27684.768908] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-metadata args:(null)
[I][27684.768949] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-metadata
[I][27684.768952] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-spa-device-factory args:(null)
[I][27684.768992] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-spa-device-factory
[I][27684.768996] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-spa-node-factory args:(null)
[I][27684.769044] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-spa-node-factory
[I][27684.769047] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-client-node args:(null)
[I][27684.769134] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-client-node
[I][27684.769138] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-client-device args:(null)
[I][27684.769177] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-client-device
[I][27684.769180] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-portal args:(null)
[I][27684.769390] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-portal
[I][27684.769395] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-access args:{
            # Socket-specific access permissions
            #access.socket = { pipewire-0 = "default", pipewire-0-manager = "unrestricted" }

            # Deprecated legacy mode (not socket-based),
            # for now enabled by default if access.socket is not specified
            #access.legacy = true
        }
[I][27684.769538] mod.access   | [ module-access.c:  340 parse_args()] Using backward-compatible legacy access mode.
[I][27684.769542] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-access
[I][27684.769544] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-adapter args:(null)
[I][27684.769604] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-adapter
[I][27684.769607] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-link-factory args:(null)
[I][27684.769664] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-link-factory
[I][27684.769668] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-session-manager args:(null)
[I][27684.769764] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-session-manager
[I][27684.769783] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-x11-bell args:{
            #sink.name = ""
            #sample.name = "bell-window-system"
            #x11.display = null
            #x11.xauthority = null
        }
[I][27684.769836] pw.module    | [   impl-module.c:  258 pw_context_load_module()] No module "libpipewire-module-x11-bell" was found
[I][27684.769839] pw.conf      | [          conf.c:  570 load_module()] 0x5636b8790430: skipping unavailable module libpipewire-module-x11-bell
[I][27684.769843] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x5636b8790430: name:libpipewire-module-jackdbus-detect args:{
            #jack.library     = libjack.so.0
            #jack.server      = null
            #jack.client-name = PipeWire
            #jack.connect     = true
            #tunnel.mode      = duplex  # source|sink|duplex
            source.props = {
                #audio.channels = 2
                #midi.ports = 1
                #audio.position = [ FL FR ]
                # extra sink properties
            }
            sink.props = {
                #audio.channels = 2
                #midi.ports = 1
                #audio.position = [ FL FR ]
                # extra sink properties
            }
        }
[I][27684.770154] pw.conf      | [          conf.c:  581 load_module()] 0x5636b8790430: loaded module libpipewire-module-jackdbus-detect
[I][27684.770158] pw.context   | [       context.c:  350 pw_context_new()] 0x5636b8790430: parsed 15 context.modules items
[I][27684.770160] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire.conf' section 'context.objects'
[I][27684.770223] pw.node      | [     impl-node.c:  972 check_properties()] 0x5636b87ce660: group '(null)'->'pipewire.dummy'
[I][27684.770254] pw.node      | [     impl-node.c:  412 node_update_state()] (Dummy-Driver-28) creating -> suspended
[I][27684.770257] pw.context   | [       context.c: 1196 pw_context_recalc_graph()] 0x5636b8790430: busy:0 reason:register active node
[I][27684.770274] pw.node      | [     impl-node.c:  972 check_properties()] 0x5636b87dbc50: group '(null)'->'pipewire.freewheel'
[I][27684.770290] pw.node      | [     impl-node.c:  412 node_update_state()] (Freewheel-Driver-29) creating -> suspended
[I][27684.770292] pw.context   | [       context.c: 1196 pw_context_recalc_graph()] 0x5636b8790430: busy:0 reason:register active node
[I][27684.770297] pw.context   | [       context.c:  355 pw_context_new()] 0x5636b8790430: parsed 2 context.objects items
[I][27684.770299] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire.conf' section 'context.exec'
[I][27684.770303] pw.context   | [       context.c:  358 pw_context_new()] 0x5636b8790430: parsed 0 context.exec items
[W][27684.770323] default      | [        thread.c:  105 impl_acquire_rt()] acquire_rt thread:0x7feba896e6c0 prio:-1 not implemented
[I][27684.770330] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:log.level type: value:3
[I][27684.770333] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.rate type: value:48000
[I][27684.770336] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][27684.770338] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.quantum type: value:1024
[I][27684.770341] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.min-quantum type: value:32
[I][27684.770345] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.max-quantum type: value:2048
[I][27684.770348] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.force-quantum type: value:0
[I][27684.770350] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x5636b87a98f0: add id:0 key:clock.force-rate type: value:0
[I][27684.770354] default      | [      pipewire.c:  110 main()] start main loop
[I][27684.770383] mod.portal   | [ module-portal.c:  157 on_portal_pid_received()] Portal is not running
[W][27684.770387] mod.jackdbus-detect | [module-jackdbus-:  212 on_is_started_received()] Failed to receive jackdbus reply: org.freedesktop.DBus.Error.ServiceUnknown: The name org.jackaudio.service was not provided by any .service files
[I][27685.764950] pw.context   | [      pipewire.c:  647 pw_init()] version 0.3.84
[I][27685.765046] pw.conf      | [          conf.c:  404 conf_load()] 0x55cd4104fd20: loaded config '/usr/share/pipewire/pipewire-pulse.conf' with 8 items
[I][27685.765060] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.properties'
[I][27685.765064] pw.context   | [       context.c:  245 pw_context_new()] 0x55cd4104f430: parsed 0 context.properties items
[I][27685.765725] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.spa-libs'
[I][27685.765739] pw.context   | [       context.c:  346 pw_context_new()] 0x55cd4104f430: parsed 2 context.spa-libs items
[I][27685.765741] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.modules'
[I][27685.765745] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-rt args:{
            nice.level   = -11
            #rt.prio      = 88
            rt.prio      = 65
            #rt.time.soft = -1
            #rt.time.hard = -1
        }
[I][27685.765801] mod.rt       | [     module-rt.c:  564 check_realtime_privileges()] Clamp rtprio 65 to 0
[I][27685.765804] mod.rt       | [     module-rt.c:  572 check_realtime_privileges()] Priority max (0) must be at least 11
[I][27685.766317] mod.rt       | [     module-rt.c:  282 pw_rtkit_check_xdg_portal()] Can't find org.freedesktop.portal.Desktop. Is xdg-desktop-portal running?
[I][27685.766324] mod.rt       | [     module-rt.c:  950 rtkit_get_bus()] found session bus but no portal, trying RTKit fallback
[E][27685.766348] mod.rt       | [     module-rt.c:  262 pw_rtkit_bus_get()] Failed to connect to system bus: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory
[W][27685.766351] mod.rt       | [     module-rt.c:  968 rtkit_get_bus()] Realtime scheduling disabled: unsufficient realtime privileges, Portal not found on session bus, and no system bus for RTKit: Connection refused
[I][27685.766366] pw.conf      | [          conf.c:  577 load_module()] 0x55cd4104f430: could not load optional module "libpipewire-module-rt": Connection refused
[I][27685.766369] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-protocol-native args:(null)
[I][27685.766624] pw.conf      | [          conf.c:  581 load_module()] 0x55cd4104f430: loaded module libpipewire-module-protocol-native
[I][27685.766628] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-client-node args:(null)
[I][27685.766724] pw.conf      | [          conf.c:  581 load_module()] 0x55cd4104f430: loaded module libpipewire-module-client-node
[I][27685.766728] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-adapter args:(null)
[I][27685.766789] pw.conf      | [          conf.c:  581 load_module()] 0x55cd4104f430: loaded module libpipewire-module-adapter
[I][27685.766792] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-metadata args:(null)
[I][27685.766834] pw.conf      | [          conf.c:  581 load_module()] 0x55cd4104f430: loaded module libpipewire-module-metadata
[I][27685.766837] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-protocol-pulse args:{
            # contents of pulse.properties can also be placed here
            # to have config per server.
        }
[I][27685.767662] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.properties'
[I][27685.767765] mod.protocol-pulse | [         utils.c:   71 get_runtime_dir()] created /var/lib/wayland/pulse
[I][27685.767790] mod.protocol-pulse | [        server.c:  619 start_unix_server()] server 0x55cd4107b130: listening on unix:/var/lib/wayland/pulse/native
[I][27685.768104] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.min.req = 128/48000
[I][27685.768107] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.default.req = 960/48000
[I][27685.768110] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.min.frag = 128/48000
[I][27685.768111] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.default.frag = 96000/48000
[I][27685.768113] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.default.tlength = 96000/48000
[I][27685.768115] mod.protocol-pulse | [  pulse-server.c: 5422 parse_frac()] : defaults: pulse.min.quantum = 128/48000
[I][27685.768118] mod.protocol-pulse | [  pulse-server.c: 5452 parse_format()] : defaults: pulse.default.format = F32LE
[I][27685.768122] mod.protocol-pulse | [  pulse-server.c: 5437 parse_position()] : defaults: pulse.default.position = [ FL FR ]
[I][27685.768123] mod.protocol-pulse | [  pulse-server.c: 5466 parse_uint32()] : defaults: pulse.idle.timeout = 0
[I][27685.768125] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'pulse.cmd'
[I][27685.768130] mod.protocol-pulse | [        module.c:   69 module_load()] load module index:536870912 name:module-always-sink
[I][27685.768135] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x55cd4104f430: name:libpipewire-module-fallback-sink args:{ }
[I][27685.768206] mod.protocol-native | [  local-socket.c:   72 try_connect()] connecting to 'pipewire-0' runtime_dir:/var/lib/wayland
[I][27685.768224] pw.conf      | [          conf.c:  581 load_module()] 0x55cd4104f430: loaded module libpipewire-module-protocol-pulse
[I][27685.768226] pw.context   | [       context.c:  350 pw_context_new()] 0x55cd4104f430: parsed 6 context.modules items
[I][27685.768228] pw.context   | [       context.c:  355 pw_context_new()] 0x55cd4104f430: parsed 0 context.objects items
[I][27685.768230] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/pipewire-pulse.conf' section 'context.exec'
[I][27685.768232] pw.context   | [       context.c:  358 pw_context_new()] 0x55cd4104f430: parsed 0 context.exec items
[I][27685.768230] mod.protocol-native | [module-protocol-:  598 client_new()] server 0x5636b87aed80: security label not available
[W][27685.768252] default      | [        thread.c:  105 impl_acquire_rt()] acquire_rt thread:0x7f86e74b96c0 prio:-1 not implemented
[I][27685.768258] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:log.level type: value:3
[I][27685.768260] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.rate type: value:48000
[I][27685.768263] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][27685.768265] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.quantum type: value:1024
[I][27685.768267] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.min-quantum type: value:32
[I][27685.768269] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.max-quantum type: value:2048
[I][27685.768271] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.force-quantum type: value:0
[I][27685.768273] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x55cd410928a0: add id:0 key:clock.force-rate type: value:0
[I][27685.768276] default      | [      pipewire.c:  110 main()] start main loop
[I][27685.768311] mod.access   | [ module-access.c:  177 context_check_access()] client 0x5636b87ea100 has trusted pid 36
[I][27685.768342] mod.access   | [ module-access.c:  215 context_check_access()] 0x5636b87bf410: client 0x5636b87ea100 'unrestricted' access granted
[I][27685.768347] pw.client    | [   impl-client.c:  741 pw_impl_client_update_permissions()] 0x5636b87ea100: set default permissions 00000000 -> 000001c8
[I][27685.768414] mod.fallback-sink | [module-fallback-:  198 sink_create()] creating fallback dummy sink
[I][27685.768458][null-audio-sink.:  997 impl_init()] null-audio-sink 0x5636b880a8b8: initialized
[I][27685.768620] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'monitor.channel-volumes' val:'true'
[I][27685.768624] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.disable' val:'false'
[I][27685.768628] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.min-volume' val:'0.000000'
[I][27685.768632] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.max-volume' val:'10.000000'
[I][27685.768635] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.normalize' val:'false'
[I][27685.768638] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.mix-lfe' val:'true'
[I][27685.768641] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.upmix' val:'true'
[I][27685.768643] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.lfe-cutoff' val:'0.000000'
[I][27685.768646] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.fc-cutoff' val:'0.000000'
[I][27685.768648] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.rear-delay' val:'0.000000'
[I][27685.768650] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.stereo-widen' val:'0.000000'
[I][27685.768653] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.hilbert-taps' val:'0'
[I][27685.768656] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.upmix-method' val:'none'
[I][27685.768658] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'resample.quality' val:'4'
[I][27685.768660] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'resample.disable' val:'false'
[I][27685.768663] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'dither.noise' val:'0'
[I][27685.768664] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'dither.method' val:'none'
[I][27685.768666] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'debug.wav-path' val:''
[I][27685.768669] spa.audioconvert | [  audioconvert.c:  932 parse_prop_params()] key:'channelmix.lock-volumes' val:'false'
[I][27685.768672][channelmix-ops.c:  227 make_matrix()] pairing UNK channels (1.000000)
[I][27685.768714] pw.node      | [     impl-node.c:  412 node_update_state()] (auto_null-0) creating -> suspended
[I][27685.768747] pw.context   | [       context.c: 1196 pw_context_recalc_graph()] 0x5636b8790430: busy:0 reason:register active node
W 05:13:40.362777             mod.rt ../src/modules/module-rt.c:262:pw_rtkit_bus_get: Failed to connect to system bus: Failed to connect to socket /run/dbus/system_bus_socket: No such file or directory
M 05:13:40.362816             mod.rt ../src/modules/module-rt.c:968:rtkit_get_bus: Realtime scheduling disabled: unsufficient realtime privileges, Portal not found on session bus, and no system bus for RTKit: Connection refused
M 05:13:40.363352            default ../src/pipewire/thread.c:105:impl_acquire_rt: acquire_rt thread:0x7fdfcf8a86c0 prio:-1 not implemented
[I][27686.777433] mod.protocol-native | [module-protocol-:  598 client_new()] server 0x5636b87aed80: security label not available
[I][27686.777536] mod.protocol-native | [module-protocol-:  598 client_new()] server 0x5636b87aed80: security label not available
[I][27686.777682] mod.access   | [ module-access.c:  177 context_check_access()] client 0x5636b883a6d0 has trusted pid 39
[I][27686.777736] mod.access   | [ module-access.c:  215 context_check_access()] 0x5636b87bf410: client 0x5636b883a6d0 'unrestricted' access granted
[I][27686.777756] pw.client    | [   impl-client.c:  741 pw_impl_client_update_permissions()] 0x5636b883a6d0: set default permissions 00000000 -> 000001c8
[I][27686.777904] mod.access   | [ module-access.c:  177 context_check_access()] client 0x5636b884d8d0 has trusted pid 39
[I][27686.777928] mod.access   | [ module-access.c:  215 context_check_access()] 0x5636b87bf410: client 0x5636b884d8d0 'unrestricted' access granted
[I][27686.777942] pw.client    | [   impl-client.c:  741 pw_impl_client_update_permissions()] 0x5636b884d8d0: set default permissions 00000000 -> 000001c8
M 05:13:40.372452          wp-device ../lib/wp/device.c:619:wp_spa_device_new_from_spa_factory: SPA handle 'api.libcamera.enum.manager' could not be loaded; is it installed?
M 05:13:40.372465   script/libcamera libcamera.lua:173:chunk: PipeWire's libcamera SPA missing or broken. libcamera not supported.
[I][27686.793640] pw.node      | [     impl-node.c:  412 node_update_state()] (Midi-Bridge-37) creating -> suspended
[I][27686.793655] pw.context   | [       context.c: 1196 pw_context_recalc_graph()] 0x5636b8790430: busy:0 reason:register active node
[I][27686.793715] pw.context   | [       context.c: 1196 pw_context_recalc_graph()] 0x5636b8790430: busy:0 reason:node flags changed
[I][27686.793786] spa.alsa     | [      alsa-seq.c:  238 alsa_seq_on_sys()] unhandled event 66: 0:1
[I][27686.793789] spa.alsa     | [      alsa-seq.c:  238 alsa_seq_on_sys()] unhandled event 66: 0:0
2023/11/14 05:13:41 [INFO] - config: no config file found, using the default config
2023/11/14 05:13:41 [DEBUG] - config: outputname:  (null)
2023/11/14 05:13:41 [DEBUG] - config: max_fps:  0.000000
2023/11/14 05:13:41 [DEBUG] - config: exec_before:  (null)
2023/11/14 05:13:41 [DEBUG] - config: exec_after:  (null)
2023/11/14 05:13:41 [DEBUG] - config: chooser_cmd: (null)
2023/11/14 05:13:41 [DEBUG] - config: chooser_type: default
2023/11/14 05:13:41 [DEBUG] - config: force_mod_linear: 0
2023/11/14 05:13:41 [DEBUG] - dbus: connected
2023/11/14 05:13:41 [DEBUG] - wlroots: wl_display connected
[I][27687.774469] pw.context   | [      pipewire.c:  647 pw_init()] version 0.3.84
2023/11/14 05:13:41 [DEBUG] - pipewire: pw_loop created
2023/11/14 05:13:41 [DEBUG] - pipewire: establishing connection to core
[I][27687.774768] pw.conf      | [          conf.c:  404 conf_load()] 0x557750a80c10: loaded config '/usr/share/pipewire/client.conf' with 5 items
[I][27687.774778] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/client.conf' section 'context.properties'
[I][27687.774782] pw.context   | [       context.c:  245 pw_context_new()] 0x557750a801d0: parsed 1 context.properties items
[I][27687.775097] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/client.conf' section 'context.spa-libs'
[I][27687.775109] pw.context   | [       context.c:  346 pw_context_new()] 0x557750a801d0: parsed 2 context.spa-libs items
[I][27687.775111] pw.conf      | [          conf.c:  957 pw_conf_section_for_each()] handle config '/usr/share/pipewire/client.conf' section 'context.modules'
[I][27687.775116] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-protocol-native args:(null)
[I][27687.775392] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-protocol-native
[I][27687.775396] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-client-node args:(null)
[I][27687.775491] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-client-node
[I][27687.775495] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-client-device args:(null)
[I][27687.775536] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-client-device
[I][27687.775539] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-adapter args:(null)
[I][27687.775596] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-adapter
[I][27687.775599] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-metadata args:(null)
[I][27687.775644] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-metadata
[I][27687.775646] pw.module    | [   impl-module.c:  157 pw_context_load_module()] 0x557750a801d0: name:libpipewire-module-session-manager args:(null)
[I][27687.775705] pw.conf      | [          conf.c:  581 load_module()] 0x557750a801d0: loaded module libpipewire-module-session-manager
[I][27687.775709] pw.context   | [       context.c:  350 pw_context_new()] 0x557750a801d0: parsed 6 context.modules items
[I][27687.775711] pw.context   | [       context.c:  355 pw_context_new()] 0x557750a801d0: parsed 0 context.objects items
[I][27687.775714] pw.context   | [       context.c:  358 pw_context_new()] 0x557750a801d0: parsed 0 context.exec items
[W][27687.775733] default      | [        thread.c:  105 impl_acquire_rt()] acquire_rt thread:0x7f8e7980d6c0 prio:-1 not implemented
[I][27687.775739] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:log.level type: value:0
[I][27687.775742] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.rate type: value:48000
[I][27687.775744] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.allowed-rates type: value:[ 48000 ]
[I][27687.775746] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.quantum type: value:1024
[I][27687.775747] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.min-quantum type: value:32
[I][27687.775749] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.max-quantum type: value:2048
[I][27687.775751] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.force-quantum type: value:0
[I][27687.775752] pw.metadata  | [ impl-metadata.c:  186 impl_set_property()] 0x557750a8ff60: add id:0 key:clock.force-rate type: value:0
[I][27687.775788] mod.protocol-native | [  local-socket.c:   72 try_connect()] connecting to 'pipewire-0' runtime_dir:/var/lib/wayland
[I][27687.775817] mod.protocol-native | [module-protocol-:  598 client_new()] server 0x5636b87aed80: security label not available
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_shm  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: |-- registered to interface wl_shm (Version 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zxdg_output_manager_v1  (Version: 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_output_manager_v1  (Version: 4)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_compositor  (Version: 5)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_subcompositor  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_data_device_manager  (Version: 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_primary_selection_device_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_seat  (Version: 8)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_virtual_pointer_manager_v1  (Version: 2)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_virtual_keyboard_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_pointer_gestures_v1  (Version: 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register xdg_wm_base  (Version: 2)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register xdg_activation_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register org_kde_kwin_server_decoration_manager  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zxdg_decoration_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wp_presentation  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_export_dmabuf_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_screencopy_manager_v1  (Version: 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: |-- registered to interface zwlr_screencopy_manager_v1 (Version 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_data_control_manager_v1  (Version: 2)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_gamma_control_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wp_viewporter  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wp_single_pixel_buffer_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register org_kde_kwin_idle  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register ext_idle_notifier_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_idle_inhibit_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_relative_pointer_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwp_pointer_constraints_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_input_inhibit_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_foreign_toplevel_manager_v1  (Version: 3)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register ext_session_lock_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_output_power_manager_v1  (Version: 1)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register zwlr_layer_shell_v1  (Version: 4)
2023/11/14 05:13:41 [DEBUG] - wlroots: interface to register wl_output  (Version: 4)
2023/11/14 05:13:41 [DEBUG] - wlroots: |-- registered to interface wl_output (Version 4)
2023/11/14 05:13:41 [DEBUG] - wayland: registry listeners run
[I][27687.778532] mod.access   | [ module-access.c:  177 context_check_access()] client 0x5636b88a0880 has trusted pid 46
[I][27687.778606] mod.access   | [ module-access.c:  215 context_check_access()] 0x5636b87bf410: client 0x5636b88a0880 'unrestricted' access granted
[I][27687.778631] pw.client    | [   impl-client.c:  741 pw_impl_client_update_permissions()] 0x5636b88a0880: set default permissions 00000000 -> 000001c8
XDP: Looking for portals configuration in '/root/.config/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/root/.config/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/etc/xdg/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/etc/xdg/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/etc/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/etc/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/root/.local/share/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/root/.local/share/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/usr/local/share/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/usr/local/share/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/usr/share/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/usr/share/xdg-desktop-portal/portals.conf'
XDP: Looking for portals configuration in '/usr/share/xdg-desktop-portal/wlroots-portals.conf'
XDP: Looking for portals configuration in '/usr/share/xdg-desktop-portal/portals.conf'
XDP: load portals from /usr/share/xdg-desktop-portal/portals
XDP: loading /usr/share/xdg-desktop-portal/portals/wlr.portal
XDP: portal implementation supports org.freedesktop.impl.portal.Screenshot
XDP: portal implementation supports org.freedesktop.impl.portal.ScreenCast
XDP: providing portal org.freedesktop.portal.MemoryMonitor
XDP: providing portal org.freedesktop.portal.PowerProfileMonitor
XDP: providing portal org.freedesktop.portal.NetworkMonitor
XDP: providing portal org.freedesktop.portal.ProxyResolver
XDP: providing portal org.freedesktop.portal.Trash
XDP: providing portal org.freedesktop.portal.GameMode

(/usr/libexec/xdg-desktop-portal:49): xdg-desktop-portal-WARNING **: 05:13:42.380: Failed to create RealtimeKit proxy: Could not connect: No such file or directory
XDP: providing portal org.freedesktop.portal.Realtime

(/usr/libexec/xdg-desktop-portal:49): xdg-desktop-portal-WARNING **: 05:13:42.380: No skeleton to export

(/usr/libexec/xdg-desktop-portal:49): xdg-desktop-portal-WARNING **: 05:13:42.380: Choosing wlr.portal for org.freedesktop.impl.portal.ScreenCast via the deprecated UseIn key

(/usr/libexec/xdg-desktop-portal:49): xdg-desktop-portal-WARNING **: 05:13:42.380: The preferred method to match portal implementations to desktop environments is to use the portals.conf(5) configuration file
XDP: Using wlr.portal for org.freedesktop.impl.portal.ScreenCast in wlroots (fallback)
XDP: providing portal org.freedesktop.portal.ScreenCast
XDP: org.freedesktop.portal.Desktop acquired
[I][27688.790604] mod.portal   | [ module-portal.c:  178 on_portal_pid_received()] Got portal pid 49

Everything looks good. Now running https://gitlab.gnome.org/-/snippets/39:

PYTHON_TEST: new_session_path: /org/freedesktop/portal/desktop/session/1_16/u1
PYTHON_TEST: screen_cast_call: method: <dbus.proxies._DeferredMethod object at 0x7f6287026690>, args: ()
PYTHON_TEST: new_request_path: /org/freedesktop/portal/desktop/request/1_16/u1
XDP: screen cast session owned by ':1.16' created
2023/11/14 05:25:18 [INFO] - dbus: create session method invoked
2023/11/14 05:25:18 [INFO] - dbus: request_handle: /org/freedesktop/portal/desktop/request/1_16/u1
2023/11/14 05:25:18 [INFO] - dbus: session_handle: /org/freedesktop/portal/desktop/session/1_16/u1
2023/11/14 05:25:18 [INFO] - dbus: app_id: 
PYTHON_TEST: on_create_session_response: 0 results: dbus.Dictionary({dbus.String('session_handle'): dbus.String('/org/freedesktop/portal/desktop/session/1_16/u1', variant_level=1)}, signature=dbus.Signature('sv'))
session /org/freedesktop/portal/desktop/session/1_16/u1 created
PYTHON_TEST: screen_cast_call: method: <dbus.proxies._ProxyMethod object at 0x7f6287087590>, args: (dbus.String('/org/freedesktop/portal/desktop/session/1_16/u1', variant_level=1),)
PYTHON_TEST: new_request_path: /org/freedesktop/portal/desktop/request/1_16/u2
2023/11/14 05:25:18 [INFO] - dbus: select sources method invoked
2023/11/14 05:25:18 [INFO] - dbus: request_handle: /org/freedesktop/portal/desktop/request/1_16/u2
2023/11/14 05:25:18 [INFO] - dbus: session_handle: /org/freedesktop/portal/desktop/session/1_16/u1
2023/11/14 05:25:18 [INFO] - dbus: app_id: 
2023/11/14 05:25:18 [DEBUG] - dbus: select sources: found matching session /org/freedesktop/portal/desktop/session/1_16/u1
2023/11/14 05:25:18 [INFO] - dbus: option types:3
2023/11/14 05:25:18 [INFO] - dbus: option multiple: 0
2023/11/14 05:25:18 [INFO] - wlroots: capturable output: Unknown model: Unknown: id: 33 name: HEADLESS-1
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser called
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser called
/bin/sh: 1: slurp: not found
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser slurp -f %o -or not found. Trying next one.
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser called
/bin/sh: 1: wofi: not found
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser wofi -d -n --prompt='Select the monitor to share:' not found. Trying next one.
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser called
/bin/sh: 1: bemenu: not found
2023/11/14 05:25:18 [DEBUG] - wlroots: output chooser bemenu --prompt='Select the monitor to share:' not found. Trying next one.
2023/11/14 05:25:18 [INFO] - xdpw: screencast instance 0x564928317910 has 1 references
2023/11/14 05:25:18 [INFO] - xdpw: 1 active screencast instances
2023/11/14 05:25:18 [INFO] - wlroots: output: HEADLESS-1
PYTHON_TEST: on_select_sources_response: 0 results: dbus.Dictionary({}, signature=dbus.Signature('sv'))
sources selected
PYTHON_TEST: screen_cast_call: method: <dbus.proxies._ProxyMethod object at 0x7f6287087590>, args: (dbus.String('/org/freedesktop/portal/desktop/session/1_16/u1', variant_level=1), '')
PYTHON_TEST: new_request_path: /org/freedesktop/portal/desktop/request/1_16/u3
2023/11/14 05:25:18 [INFO] - dbus: start method invoked
2023/11/14 05:25:18 [INFO] - dbus: request_handle: /org/freedesktop/portal/desktop/request/1_16/u3
2023/11/14 05:25:18 [INFO] - dbus: session_handle: /org/freedesktop/portal/desktop/session/1_16/u1
2023/11/14 05:25:18 [INFO] - dbus: app_id: 
2023/11/14 05:25:18 [INFO] - dbus: parent_window: 
2023/11/14 05:25:18 [DEBUG] - dbus: start: found matching session /org/freedesktop/portal/desktop/session/1_16/u1
[  52259.253]  -> zwlr_screencopy_manager_v1#5.capture_output(new id zwlr_screencopy_frame_v1#3, 1, wl_output#6)
[  52259.281] zwlr_screencopy_manager_v1#5.capture_output(new id zwlr_screencopy_frame_v1#3, 1, wl_output#6)
[  52259.300]  -> zwlr_screencopy_frame_v1#3.buffer(1, 1280, 720, 5120)
[  52259.304]  -> zwlr_screencopy_frame_v1#3.buffer_done()
[  52259.319] zwlr_screencopy_frame_v1#3.buffer(1, 1280, 720, 5120)
[  52259.325] zwlr_screencopy_frame_v1#3.buffer_done()
[  52259.328]  -> zwlr_screencopy_frame_v1#3.destroy()
[  52259.333]  -> wl_display#1.sync(new id wl_callback#7)
[  52259.343] zwlr_screencopy_frame_v1#3.destroy()
[  52259.349]  -> wl_display#1.delete_id(3)
[  52259.352] wl_display#1.sync(new id wl_callback#7)
[  52259.355]  -> wl_callback#7.done(7)
[  52259.358]  -> wl_display#1.delete_id(7)
[  52259.368] wl_display#1.delete_id(3)
[  52259.372] wl_display#1.delete_id(7)
[  52259.374] wl_callback#7.done(7)
2023/11/14 05:25:18 [INFO] - wlroots: unable to receive a valid format from wlr_screencopy

(/usr/libexec/xdg-desktop-portal:49): xdg-desktop-portal-WARNING **: 05:25:18.081: A backend call failed: Operation not permitted
XDP: screen cast session owned by ':1.16' closed
2023/11/14 05:25:18 [INFO] - dbus: session closed
PYTHON_TEST: on_start_response: 2, results: dbus.Dictionary({}, signature=dbus.Signature('sv'))
2023/11/14 05:25:18Failed to start: 2
 [DEBUG] - dbus: destroying session 0x5649283148f0
2023/11/14 05:25:18 [DEBUG] - xdpw: screencast instance 0x564928317910 now has 0 references
2023/11/14 05:25:18 [DEBUG] - xdpw: destroying cast instance

Seems it was able to capture a frame, but the format is invalid. What could be wrong here?

Looks like it's a linux_dma issue. Seems screencast_version >= 3 work only with DMABUF and ignore WL_SHM, not sure if it's a bug or not. So the question is why

wlroots: linux_dmabuf event handler

is not being triggered in my env?

According to the source code, it appears that only the gles2 and vulkan renderers support DMA. I had been using pixman. The question arises: should this project include support for the pixman renderer? If it doesn't, then it might be advisable to eliminate it from the list of supported renderers since it serves no purpose in this context.

Ran into this as well while trying to run a device that doesn't have a DRM node - there doesn't seem to be a way to use the screen cast protocol without one as a result of this issue, and since we can't use the gles2 or vulkan renderer with LLVMPIPE (see https://gitlab.freedesktop.org/wlroots/wlroots/-/issues/2871). Is there a way to request the older screencast_version explicitly to support that use case still?