Recording a region doesn't seem to work, recording pauses immediately #302
Comments
|
Same issue |
|
Seeing this as well for capturing a monitor/window, however I can't even get to the region selection as it gets stuck on loading: Edit: Restarting fixed both issues. Will try to grab some more useful info if it happens again |
|
By "restarting fixed both issues" do you mean you were able to record the area successfully? |
Yep, I could both record windows/monitors and capture screen regions |
|
That's so weird, that will make this harder to debug. In my case the only indication that something went wrong before it pauses is this message in the terminal: (I'm using a WM with no inhibit interface) I'm planning to investigate further today. |
That errors seems unrelated with the issue, you may need to enable debug logs via |
|
Based on the log it seems like a pipewire issue. Kooha sets the pipeline to Playing but it never actually plays. I see "Changed from Paused to Playing" events for each element in the pipeline but no "Pipeline changed state from Paused to Playing" like I do with the working full screen recording. |
|
After clearing config with |
Yep it seems to reliably work now after running |
|
Ran into the same issue: |
|
Did you reset dconf as described? |
|
Sure: |
|
Getting the dconf values (where the issue is reproducible) would be helpful in providing insights on what is causing the issue. I'm not sure how to get it outside flatpak, but the file should be somewhere in |
|
I'm using it on NixOS, and after the upgrading, it works now. |
Hi and thanks for the tool 🙂
2.3.0
Debug Info
Running on EndeavourOS but it now basically is a normal Arch install.
My Window Manager is Sway
Bug summary
When opening Kooha, selecting a region and then starting a recording, Kooha counts down and immediately pauses the recording. When trying to stop most of the times the app freezes and needs to be killed with
SIGTERM.Sometimes it works though. but there are a lot of Warnings.
If i can get it to work ill post the logs of a working recording later.
On full screen recordings, it always works.
Steps to reproduce
Expected behavior
Record a region.
Relevant logs, screenshots, screencasts, etc.
Failed Log
2024-06-23T13:49:55.625113Z INFO kooha::application: Kooha (io.github.seadve.Kooha) 2024-06-23T13:49:55.625121Z INFO kooha::application: Version: 2.3.0 () 2024-06-23T13:49:55.625123Z INFO kooha::application: Datadir: /usr/share/kooha 2024-06-23T13:49:57.686965Z DEBUG kooha::recording: Created screencast proxy version=Ok(5) available_cursor_modes=Ok(CursorMode(HIDDEN | EMBEDDED)) available_source_types=Ok(SourceType(MONITOR)) 2024-06-23T13:49:57.688160Z DEBUG kooha::recording: Selecting sources cursor_mode=CursorMode(EMBEDDED) source_type=SourceType(MONITOR) is_multiple_sources=false restore_token="" persist_mode=Persistent 2024-06-23T13:49:58.403030Z DEBUG kooha::recording: Starting session window_identifier=wayland:b294be3387fbd631b6ea21ddf0e852d8 [I][01571.893988] pw.context | [ pipewire.c: 647 pw_init()] version 1.0.6 2024-06-23T13:49:58.423625Z DEBUG kooha::area_selector: Using gl pipeline [I][01571.900303] pw.conf | [ conf.c: 408 conf_load()] 0x5957ac2e52d0: loaded config '/etc/pipewire/client.conf' with 5 items [I][01571.900334] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.properties' [I][01571.900344] pw.context | [ context.c: 253 pw_context_new()] 0x5957ac2ea1c0: parsed 1 context.properties items [I][01571.900953] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.spa-libs' [I][01571.900973] pw.context | [ context.c: 354 pw_context_new()] 0x5957ac2ea1c0: parsed 2 context.spa-libs items [I][01571.900981] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.modules' [I][01571.900988] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-protocol-native args:(null) [I][01571.901195] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-protocol-native [I][01571.901208] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-client-node args:(null) [I][01571.901392] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-client-node [I][01571.901402] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-client-device args:(null) [I][01571.901522] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-client-device [I][01571.901533] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-adapter args:(null) [I][01571.901657] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-adapter [I][01571.901667] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-metadata args:(null) [I][01571.901790] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-metadata [I][01571.901800] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957ac2ea1c0: name:libpipewire-module-session-manager args:(null) [I][01571.901931] pw.conf | [ conf.c: 592 load_module()] 0x5957ac2ea1c0: loaded module libpipewire-module-session-manager [I][01571.901940] pw.context | [ context.c: 358 pw_context_new()] 0x5957ac2ea1c0: parsed 6 context.modules items [I][01571.901947] pw.context | [ context.c: 363 pw_context_new()] 0x5957ac2ea1c0: parsed 0 context.objects items [I][01571.901954] pw.context | [ context.c: 366 pw_context_new()] 0x5957ac2ea1c0: parsed 0 context.exec items [I][01571.901999] default | [ thread.c: 105 impl_acquire_rt()] acquire_rt thread:0x70d9696006c0 prio:-1 not implemented [I][01571.902013] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:log.level type: value:0 [I][01571.902021] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.rate type: value:48000 [I][01571.902028] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.allowed-rates type: value:[ 48000 ] [I][01571.902034] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.quantum type: value:1024 [I][01571.902040] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.min-quantum type: value:32 [I][01571.902046] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.max-quantum type: value:2048 [I][01571.902052] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.force-quantum type: value:0 [I][01571.902058] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2eb8d0: add id:0 key:clock.force-rate type: value:0 [I][01571.902136] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'stream.properties' [E][01571.902187] mod.protocol-native | [module-protocol-: 1009 process_remote()] 0x5957ac2ebe50: could not find proxy 2 [E][01571.902204] mod.protocol-native | [module-protocol-: 1009 process_remote()] 0x5957ac2ebe50: could not find proxy 2 [E][01571.902214] mod.protocol-native | [module-protocol-: 1009 process_remote()] 0x5957ac2ebe50: could not find proxy 2 0:00:02.812624777 345268 0x70d970001b70 FIXME default gstutils.c:4088:gst_element_decorate_stream_id_internal:<pipewiresrc0> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 0:00:02.812842269 345268 0x70d930007b20 FIXME glcontext gstglcontext.c:2038:gst_gl_wrapped_context_get_config:<glwrappedcontext0> wrapped context could not retrieve config. The application may be missing a call to gst_gl_context_fill_info() or the specific platform implemention is not implemented for retrieving the config from a wrapped OpenGL context. [I][01571.911135] pw.stream | [ stream.c: 894 parse_latency()] stream 0x5957ac317120: set output latency 0,000000-0,000000 0-0 0-0 [I][01571.921342] pw.stream | [ stream.c: 698 impl_send_command()] 0x5957ac317120: command Spa:Pod:Object:Command:Node:Start [I][01571.921381] pw.node | [ impl-node.c: 412 node_update_state()] (kooha-80) creating -> running 2024-06-23T13:49:58.470008Z DEBUG kooha::area_selector: Pipeline changed state from `Null` -> `Ready` 2024-06-23T13:49:58.470055Z DEBUG kooha::area_selector: Pipeline changed state from `Ready` -> `Paused` 2024-06-23T13:49:58.506368Z DEBUG kooha::area_selector: Pipeline changed state from `Paused` -> `Playing` [I][01573.296098] pw.stream | [ stream.c: 698 impl_send_command()] 0x5957ac317120: command Spa:Pod:Object:Command:Node:Pause [I][01573.296147] pw.node | [ impl-node.c: 412 node_update_state()] (kooha-80) running -> idle [I][01573.296903] pw.stream | [ stream.c: 698 impl_send_command()] 0x5957ac317120: command Spa:Pod:Object:Command:Node:Suspend [I][01573.296962] pw.node | [ impl-node.c: 412 node_update_state()] (kooha-80) idle -> suspended [I][01573.297023] pw.node | [ impl-node.c: 2044 pw_impl_node_destroy()] (kooha-80) destroy 2024-06-23T13:49:59.826883Z DEBUG kooha::pipeline: file_path=/home/ryuko/Videos/Kooha/Kooha-2024-06-23-15-49-58.mp4 framerate=Fraction(Ratio { numer: 24, denom: 1 }) profile="mp4" fd=17 stream_len=1 streams=[Stream { node_id: 92, id: None, position: Some((0, 0)), size: Some((1920, 1080)), source_type: Some(SourceType(WINDOW)) }] record_desktop_audio=true record_microphone=false select_area_data=Some(SelectAreaData { selection: Selection { x: 373.10156, y: 15.949216, width: 366.89844, height: 398.05078 }, paintable_rect: Rect { x: 4.0, y: 0.0, width: 736.0, height: 414.0 }, stream_size: (1920, 1080) }) 2024-06-23T13:49:59.827492Z DEBUG kooha::pipeline: raw_top_crop=41.606651306152344 raw_left_crop=962.8736572265625 raw_right_crop=0.0 raw_bottom_crop=-0.0001220703125 2024-06-23T13:49:59.827501Z DEBUG kooha::pipeline: top_crop=42 left_crop=962 right_crop=0 bottom_crop=0 2024-06-23T13:49:59.837063Z DEBUG kooha::device: Finding device name for class `Sink` 2024-06-23T13:49:59.837072Z DEBUG kooha::device: Skipping device `pulsedevice0` as it has unknown device class `Audio/Source` 2024-06-23T13:49:59.837082Z DEBUG kooha::device: Skipping device `pulsedevice1` as it has unknown device class `Audio/Source` 2024-06-23T13:49:59.837089Z DEBUG kooha::device: Skipping device `pulsedevice2` as it has unknown device class `Audio/Source` 2024-06-23T13:49:59.837102Z DEBUG kooha::device: Skipping device `pulsedevice3` as it is not the default 2024-06-23T13:49:59.837283Z DEBUG kooha::pipeline: Found desktop audio with name `alsa_output.usb-Focusrite_Scarlett_Solo_USB_Y7THA7X0535FB2-00.pro-output-0.monitor` [I][01576.313930] pw.conf | [ conf.c: 408 conf_load()] 0x5957abe56180: loaded config '/etc/pipewire/client.conf' with 5 items [I][01576.313980] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.properties' [I][01576.313994] pw.context | [ context.c: 253 pw_context_new()] 0x5957abdbef80: parsed 1 context.properties items [I][01576.314276] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.spa-libs' [I][01576.314304] pw.context | [ context.c: 354 pw_context_new()] 0x5957abdbef80: parsed 2 context.spa-libs items [I][01576.314315] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'context.modules' [I][01576.314327] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-protocol-native args:(null) [I][01576.314600] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-protocol-native [I][01576.314613] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-client-node args:(null) [I][01576.314834] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-client-node [I][01576.314847] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-client-device args:(null) [I][01576.314982] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-client-device [I][01576.314994] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-adapter args:(null) [I][01576.315129] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-adapter [I][01576.315141] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-metadata args:(null) [I][01576.315277] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-metadata [I][01576.315288] pw.module | [ impl-module.c: 157 pw_context_load_module()] 0x5957abdbef80: name:libpipewire-module-session-manager args:(null) [I][01576.315437] pw.conf | [ conf.c: 592 load_module()] 0x5957abdbef80: loaded module libpipewire-module-session-manager [I][01576.315449] pw.context | [ context.c: 358 pw_context_new()] 0x5957abdbef80: parsed 6 context.modules items [I][01576.315457] pw.context | [ context.c: 363 pw_context_new()] 0x5957abdbef80: parsed 0 context.objects items [I][01576.315464] pw.context | [ context.c: 366 pw_context_new()] 0x5957abdbef80: parsed 0 context.exec items [I][01576.315496] default | [ thread.c: 105 impl_acquire_rt()] acquire_rt thread:0x70d92ea006c0 prio:-1 not implemented [I][01576.315509] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:log.level type: value:0 [I][01576.315518] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.rate type: value:48000 [I][01576.315526] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.allowed-rates type: value:[ 48000 ] [I][01576.315534] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.quantum type: value:1024 [I][01576.315541] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.min-quantum type: value:32 [I][01576.315549] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.max-quantum type: value:2048 [I][01576.315557] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.force-quantum type: value:0 [I][01576.315564] pw.metadata | [ impl-metadata.c: 186 impl_set_property()] 0x5957ac2d69a0: add id:0 key:clock.force-rate type: value:0 [I][01576.315616] pw.conf | [ conf.c: 972 pw_conf_section_for_each()] handle config '/etc/pipewire/client.conf' section 'stream.properties' 0:00:07.226311232 345268 0x70d970001490 WARN aggregator gstaggregator.c:2283:gst_aggregator_query_latency_unlocked:<audiomixer0> Latency query failed 0:00:07.226322772 345268 0x70d970001490 WARN aggregator gstaggregator.c:2283:gst_aggregator_query_latency_unlocked:<mp4mux3> Latency query failed 0:00:07.226617618 345268 0x70d970001250 WARN aggregator gstaggregator.c:2283:gst_aggregator_query_latency_unlocked:<audiomixer0> Latency query failed 0:00:07.226620528 345268 0x70d970001b70 FIXME default gstutils.c:4088:gst_element_decorate_stream_id_internal:<kooha-desktop-audio-src> Creating random stream-id, consider implementing a deterministic way of creating a stream-id 0:00:07.226650380 345268 0x70d970001250 WARN aggregator gstaggregator.c:2283:gst_aggregator_query_latency_unlocked:<audiomixer0> Latency query failed 0:00:07.226685752 345268 0x70d970001070 FIXME default gstutils.c:4088:gst_element_decorate_stream_id_internal:<pipewiresrc1> Creating random stream-id, consider implementing a deterministic way of creating a stream-id [I][01576.331480] pw.stream | [ stream.c: 894 parse_latency()] stream 0x5957ac213100: set output latency 0,000000-0,000000 0-0 0-0 [I][01576.332667] pw.stream | [ stream.c: 698 impl_send_command()] 0x5957ac213100: command Spa:Pod:Object:Command:Node:Start [I][01576.332707] pw.node | [ impl-node.c: 412 node_update_state()] (kooha-113) creating -> running 2024-06-23T13:50:02.869794Z DEBUG kooha::recording: Pipeline changed state from `Null` -> `Ready` 2024-06-23T13:50:02.869930Z DEBUG kooha::recording: Pipeline changed state from `Ready` -> `Paused` 2024-06-23T13:50:02.870662Z DEBUG kooha::window: Inhibited logout and idle (kooha:345268): Gdk-WARNING **: 15:50:23.988: Compositor doesn't support moving popups, relying on remapping 2024-06-23T13:50:24.168262Z DEBUG kooha::recording: Sending eos event to pipelineThe text was updated successfully, but these errors were encountered: