Files
Medios-Macina/mpv_logs_with_db.txt

501 lines
38 KiB
Plaintext
Raw Normal View History

2026-02-04 16:59:04 -08:00
Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
DEBUG: MPV log file: C:\Forgejo\Medios-Macina\Log\medeia-mpv.log
DEBUG: Named pipe not available yet: \\.\pipe\mpv-medios-macina
DEBUG: Named pipe not available yet: \\.\pipe\mpv-medios-macina
MPV log file: C:\Forgejo\Medios-Macina\Log\medeia-mpv.log
DEBUG: Named pipe not available yet: \\.\pipe\mpv-medios-macina
DEBUG: Named pipe not available yet: \\.\pipe\mpv-medios-macina
MPV log (tail):
[ 34.120][d][ao/wasapi] Fixing format
[ 34.120][d][ao/wasapi] IAudioClient::GetDevicePeriod
[ 34.121][v][ao/wasapi] Device period: default 10000 us, minimum 3000 us
[ 34.121][d][ao/wasapi] IAudioClient::Initialize
[ 34.134][d][ao/wasapi] IAudioClient::Initialize pRenderClient
[ 34.134][d][ao/wasapi] IAudioClient::Initialize IAudioClient_SetEventHandle
[ 34.134][d][ao/wasapi] IAudioClient::Initialize IAudioClient_GetBufferSize
[ 34.134][v][ao/wasapi] Buffer frame count: 1056 (22000 us)
[ 34.134][v][ao/wasapi] IAudioClock::GetFrequency gave a frequency of 384000.
[ 34.136][d][ao/wasapi] IAudioClient::Initialize pAudioVolume
[ 34.136][d][ao/wasapi] Entering dispatch loop
[ 34.136][d][ao/wasapi] Init wasapi done
[ 34.136][v][ao/wasapi] device buffer: 1056 samples.
[ 34.136][v][ao/wasapi] using soft-buffer of 9600 samples.
[ 34.136][i][cplayer] AO: [wasapi] 48000Hz stereo 2ch float
[ 34.136][v][cplayer] AO: Description: Windows WASAPI audio output (event mode)
[ 34.136][v][autoconvert] inserting resampler
[ 34.136][v][swresample] format change, reinitializing resampler
[ 34.136][v][swresample] 44100Hz stereo floatp -> 48000Hz stereo float
[ 34.137][v][af] [out] 48000Hz stereo 2ch float
[ 34.139][v][cplayer] audio ready
[ 34.139][v][cplayer] starting audio playback
[ 34.139][d][ao/wasapi] Thread Resume
[ 34.139][d][ao/wasapi] Thread Reset
[ 34.139][d][ao/wasapi] Thread Pause
[ 34.139][d][ao/wasapi] Thread Unpause
[ 34.139][v][cplayer] playback restart complete @ 0.000000, audio=playing, video=eof
[ 34.140][v][cplayer] Set property: user-data/medeia-pipeline-response="" -> 1
[ 34.141][v][cplayer] Set property: user-data/medeia-pipeline-request="{\"data\":{\"url\":\"https://www.youtube.com/watch?v=dQw4w9WgXcQ\"},\"op\":\"ytdlp-formats\",\"id\":\"34082-728967\"}" -> 1
[ 34.148][d][timeline] stream 0: resize index to 256
[ 34.173][v][lavf] EOF reached.
[ 34.173][v][timeline] EOF reached.
[ 35.393][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 35.393][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 35.393][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 35.399][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 35.399][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 35.399][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 35.484][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 35.484][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 35.484][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 35.490][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 35.490][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 35.490][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 35.962][v][ipc_33] Client connected
[ 35.963][v][cplayer] Set property: options/log-file="C:\\Forgejo\\Medios-Macina\\Log\\medeia-mpv.log" -> 1
[ 35.965][v][ipc_33] Client disconnected
[ 35.965][d][ipc_33] Destroying client handle...
[ 35.966][v][ipc_34] Client connected
[ 35.967][v][cplayer] Set property: options/msg-level="all=v" -> 1
[ 35.969][v][ipc_34] Client disconnected
[ 35.969][d][ipc_34] Destroying client handle...
[ 35.970][v][ipc_35] Client connected
[ 35.971][i][cplayer] medeia: log enabled -> C:\Forgejo\Medios-Macina\Log\medeia-mpv.log
[ 35.972][v][ipc_35] Client disconnected
[ 35.972][d][ipc_35] Destroying client handle...
[ 35.973][v][ipc_36] Client connected
[ 35.973][v][ipc_36] Client disconnected
[ 35.973][v][ipc_37] Client connected
[ 35.973][d][ipc_36] Destroying client handle...
[ 35.974][v][cplayer] Set property: options/log-file="C:\\Forgejo\\Medios-Macina\\Log\\medeia-mpv.log" -> 1
[ 35.975][v][ipc_37] Client disconnected
[ 35.975][d][ipc_37] Destroying client handle...
[ 35.975][v][ipc_38] Client connected
[ 35.977][v][cplayer] Set property: options/msg-level="all=v" -> 1
[ 35.978][v][ipc_38] Client disconnected
[ 35.979][d][ipc_38] Destroying client handle...
[ 35.980][v][ipc_39] Client connected
[ 35.981][v][cplayer] Set property: options/log-file="C:\\Forgejo\\Medios-Macina\\Log\\medeia-mpv.log" -> 1
[ 35.983][v][ipc_39] Client disconnected
[ 35.983][d][ipc_39] Destroying client handle...
[ 35.984][v][ipc_40] Client connected
[ 35.985][v][cplayer] Set property: options/msg-level="all=v" -> 1
[ 35.986][v][ipc_40] Client disconnected
[ 35.986][d][ipc_40] Destroying client handle...
[ 35.986][v][ipc_41] Client connected
[ 35.987][i][cplayer] medeia: log enabled -> C:\Forgejo\Medios-Macina\Log\medeia-mpv.log
[ 35.989][v][ipc_41] Client disconnected
[ 35.989][d][ipc_41] Destroying client handle...
[ 36.180][d][cplayer] Run command: cycle, flags=73, args=[name="pause", value="1.000000"]
[ 36.180][v][cplayer] Set property: pause -> 1
[ 36.180][d][ao/wasapi] Thread Pause
[ 37.104][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 37.104][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 37.104][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 37.576][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 37.583][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 37.583][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 38.287][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 38.287][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 38.287][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 38.693][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 38.693][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 38.693][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 38.965][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 38.965][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 38.965][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 38.984][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 38.990][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 38.990][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 39.098][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 39.098][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 39.105][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 39.112][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 39.112][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 39.112][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 41.106][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 41.106][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 41.106][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 41.115][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 41.122][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 41.122][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 42.198][d][cplayer] Run command: begin-vo-dragging, flags=73, args=[]
[ 42.814][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 42.814][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 42.828][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 42.828][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 42.835][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 42.835][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_right", flags="allow-hide-cursor+allow-vo-dragging"]
[ 42.835][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 42.835][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 42.856][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 42.856][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_right"]
[ 42.856][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 42.856][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 44.274][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 44.274][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_right", flags="allow-hide-cursor+allow-vo-dragging"]
[ 44.274][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 44.274][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 44.283][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 44.283][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_right"]
[ 44.283][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 44.283][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 44.297][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 44.297][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 44.297][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 44.305][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 44.305][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 44.305][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 45.258][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 45.258][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 45.258][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 45.264][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 45.270][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 45.271][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 46.370][d][cplayer] Run command: begin-vo-dragging, flags=73, args=[]
[ 48.743][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 48.744][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 50.556][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 50.556][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 50.682][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 50.682][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 50.696][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 50.696][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 50.710][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 50.710][d][cplayer] Run command: enable-section, flags=64, args=[name="wheel", flags="allow-hide-cursor+allow-vo-dragging"]
[ 50.710][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 50.786][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left_dbl"]
[ 50.786][d][cplayer] Run command: disable-section, flags=64, args=[name="wheel"]
[ 50.786][d][cplayer] Run command: disable-section, flags=64, args=[name="mbtn_left"]
[ 52.125][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left_dbl", flags=""]
[ 52.125][d][cplayer] Run command: enable-section, flags=64, args=[name="mbtn_left", flags=""]
[ 52.788][d][cplayer] Run command: script-binding, flags=73, args=[name="uosc/__keybinding1", arg=""]
[ 52.789][d][cplayer] Run command: quit, flags=73, args=[code="0"]
[ 52.789][v][cplayer] EOF code: 5
[ 52.789][d][ad] Uninit decoder.
[ 52.789][d][ao/wasapi] Thread Reset
[ 52.789][d][ao/wasapi] Thread Pause
[ 52.790][d][cplayer] Terminating demuxers...
[ 52.791][d][ffmpeg] AVIOContext: Statistics: 1300631 bytes read, 0 seeks
[ 52.791][d][cplayer] Done terminating demuxers.
[ 52.791][v][cplayer] finished playback, success (reason 3)
[ 52.791][v][cplayer] Running hook: ytdl_hook/on_after_end_file
[ 52.792][v][auto_profiles] Re-evaluating auto profile manga
[ 52.793][v][auto_profiles] Re-evaluating auto profile loop-short
[ 52.793][d][cplayer] Run command: del, flags=64, args=[name="user-data/mpv/ytdl/json-subprocess-result"]
[ 52.793][i][cplayer] Exiting... (Quit)
[ 52.794][d][cplayer] Run command: del, flags=64, args=[name="user-data/mpv/console"]
[ 52.794][d][ipc_8] Destroying client handle...
[ 52.794][d][positioning] Destroying client handle...
[ 52.794][d][commands] Destroying client handle...
[ 52.794][d][select] Destroying client handle...
[ 52.794][d][ipc_3] Destroying client handle...
[ 52.794][d][ipc_32] Destroying client handle...
[ 52.794][d][cplayer] Run command: keybind, flags=64, args=[name="q", cmd="quit", comment=""]
[ 52.794][d][console] Destroying client handle...
[ 52.794][d][auto_profiles] Destroying client handle...
[ 52.795][d][stats] Destroying client handle...
[ 52.796][d][main] Destroying client handle...
[ 52.796][d][ytdl_hook] Destroying client handle...
[ 52.798][d][uosc] Destroying client handle...
[ 52.799][d][SystemMediaTransportControls] Destroying client handle...
[ 52.805][d][ao/wasapi] Uninit wasapi
[ 52.805][d][ao/wasapi] Thread Reset
[ 52.805][d][ao/wasapi] Thread Pause
[ 52.805][d][ao/wasapi] Thread shutdown
[ 52.805][d][ao/wasapi] Thread uninit done
[ 52.805][d][ao/wasapi] Thread return
[ 52.806][d][ao/wasapi] Uninit wasapi done
[ 52.851][v][vo/gpu-next/win32] uninit
Helper logs from database (mpv module, most recent first):
[ERROR] Load URL pipeline failed: timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[WARN] Load URL still processing after 5 seconds
[INFO] [mpv error] ytdl_hook ERROR: Unsupported URL: https://example.com/
[INFO] [mpv error] ytdl_hook youtube-dl failed: unexpected error occurred
[INFO] [mpv error] cplayer Failed to recognize file format.
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [mpv error] vo/gpu-next/libplacebo Failed creating FBO texture! Disabling advanced rendering..
[INFO] [mpv error] vo/gpu-next/libplacebo Failed dispatching scaler.. disabling
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [mpv] (previous line repeated 4x)
[INFO] [mpv warn] input No key binding found for key ';'.
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv] (previous line repeated 2x)
[INFO] [mpv] (previous line repeated 4x)
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv] (previous line repeated 4x)
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv] (previous line repeated 2x)
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv] (previous line repeated 3x)
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv] (previous line repeated 3x)
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [mpv] (previous line repeated 4x)
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [mpv warn] input No key binding found for key 'MBTN_RIGHT_DBL'.
[INFO] [helper] version=2025-12-19 started ipc=\\.\pipe\mpv-medios-macina
[INFO] [helper] file=C:\Forgejo\Medios-Macina\MPV\pipeline_helper.py cwd=C:\Forgejo\Medios-Macina
[INFO] [helper] config_root=C:\Forgejo\Medios-Macina exists=False
[INFO] [helper] requested mpv log messages level=warn
[INFO] [helper] ready heartbeat armed prop=user-data/medeia-pipeline-ready
[INFO] [py] Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix,
[INFO] [py] telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-02-02T02:46:41.638481Z
[INFO] [py] DEBUG: config_dir=C:\Forgejo\Medios-Macina choices=2
[INFO] [helper] startup store-choices count=2 items=local, rpi
[INFO] [helper] published store-choices to user-data/medeia-store-choices-cached
[INFO] [helper] published config temp to user-data/medeia-config-temp=C:\Users\Admin\AppData\Local\Temp
[INFO] [helper] connected to ipc=\\.\pipe\mpv-medios-macina
[INFO] [mpv] (previous line repeated 3x)
Helper log file: C:\Forgejo\Medios-Macina\Log\medeia-mpv-helper.log
Helper log (tail):
[lua] [2026-02-04 16:56:11] medeia lua loaded version=2025-12-24 script=main
[lua] [2026-02-04 16:56:11] medeia-lua loaded version=2025-12-24
[lua] [2026-02-04 16:56:11] stores: cache_read cached_json=nil len=0
[lua] [2026-02-04 16:56:11] stores: cache_empty cached_json=nil
[lua] [2026-02-04 16:56:11] stores: requesting store-choices via helper (fallback)
[lua] [2026-02-04 16:56:11] stores: failed to load store choices via helper; success=false choices_type=nil stderr= error=helper not running
[lua] [2026-02-04 16:56:12] [KEY] attempting to re-register mbtn_right after UOSC loaded
[lua] [2026-02-04 16:56:26] [input.conf] medios-show-menu called
[lua] [2026-02-04 16:56:26] [MENU] M.show_menu called
[lua] [2026-02-04 16:56:26] [MENU] Built 6 menu items
[lua] [2026-02-04 16:56:26] [MENU] ensure_uosc_loaded returned: true
[lua] [2026-02-04 16:56:26] [MENU] Sending menu JSON to uosc: {"title":"Medios Macina","items":[{"title":"Load URL","value":"script-message medios-load-url"},{"value":"script-binding medios-info","title":"Get Metadata","hint":"Ctrl+i"},{"value":"script-binding m...
[lua] [2026-02-04 16:56:26] [MENU] Menu command sent successfully
[lua] [2026-02-04 16:56:27] medios-load-url handler called
[lua] [2026-02-04 16:56:27] medios-load-url: closing main menu before opening Load URL prompt
[lua] [2026-02-04 16:56:27] open_load_url_prompt called
[lua] [2026-02-04 16:56:27] open_load_url_prompt: sending menu to uosc
[lua] [2026-02-04 16:56:33] [LOAD-URL] Event handler called with: {"type":"search","query":"https://www.youtube.com/watch?v=3IpPonmYx3g","menu_id":"{root}"}
[lua] [2026-02-04 16:56:33] [LOAD-URL] Parsed event: type=search, query=https://www.youtube.com/watch?v=3IpPonmYx3g
[lua] [2026-02-04 16:56:33] [LOAD-URL] Trimmed URL: "https://www.youtube.com/watch?v=3IpPonmYx3g"
[lua] [2026-02-04 16:56:33] [INFO] Load URL started: https://www.youtube.com/watch?v=3IpPonmYx3g
[lua] [2026-02-04 16:56:33] [LOAD-URL] Starting to load: https://www.youtube.com/watch?v=3IpPonmYx3g
[lua] [2026-02-04 16:56:33] [LOAD-URL] Checking if URL can be loaded directly: false
[lua] [2026-02-04 16:56:33] [LOAD-URL] URL requires pipeline helper for processing
[lua] [2026-02-04 16:56:33] [LOAD-URL] Pipeline helper ready: true
[lua] [2026-02-04 16:56:33] [LOAD-URL] Sending to pipeline: .mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[lua] [2026-02-04 16:56:33] [LOAD-URL] Pipeline helper ready: true
[lua] [2026-02-04 16:56:33] M.run_pipeline called with cmd: .mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[lua] [2026-02-04 16:56:34] ipc-async: send request id=22550-814785 cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[lua] [2026-02-04 16:56:35] [LOAD-URL] Event handler called with: {"type":"close"}
[lua] [2026-02-04 16:56:35] [LOAD-URL] Parsed event: type=close, query=nil
[lua] [2026-02-04 16:56:35] [LOAD-URL] Event type is not search: close
[lua] [2026-02-04 16:56:35] [LOAD-URL] Closing menu due to type mismatch
[lua] [2026-02-04 16:56:38] [WARN] Load URL still processing after 5 seconds
[lua] [2026-02-04 16:56:38] [LOAD-URL] Timeout message shown (helper still processing)
[lua] [2026-02-04 16:56:44] M.run_pipeline callback fired: resp=nil, err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[lua] [2026-02-04 16:56:44] pipeline failed cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[lua] [2026-02-04 16:56:44] [LOAD-URL] Pipeline callback received: resp=nil, err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[lua] [2026-02-04 16:56:44] [LOAD-URL] Pipeline error: timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[lua] [2026-02-04 16:56:44] [ERROR] Load URL pipeline failed: timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[lua] [2026-02-04 16:56:44] [LOAD-URL] Closing menu
[lua] [2026-02-04 16:56:44] [LOAD-URL] Sending close-menu command to UOSC
[lua] [2026-02-04 16:56:45] ipc-async: send request id=34082-728967 op=ytdlp-formats
Lua log file: C:\Forgejo\Medios-Macina\Log\medeia-mpv-lua.log
Lua log (tail):
[2026-02-04 16:56:11] medeia lua loaded version=2025-12-24 script=main
[2026-02-04 16:56:11] medeia-lua loaded version=2025-12-24
[2026-02-04 16:56:11] stores: cache_read cached_json=nil len=0
[2026-02-04 16:56:11] stores: cache_empty cached_json=nil
[2026-02-04 16:56:11] stores: requesting store-choices via helper (fallback)
[2026-02-04 16:56:11] stores: failed to load store choices via helper; success=false choices_type=nil stderr= error=helper not running
[2026-02-04 16:56:12] [KEY] attempting to re-register mbtn_right after UOSC loaded
[2026-02-04 16:56:26] [input.conf] medios-show-menu called
[2026-02-04 16:56:26] [MENU] M.show_menu called
[2026-02-04 16:56:26] [MENU] Built 6 menu items
[2026-02-04 16:56:26] [MENU] ensure_uosc_loaded returned: true
[2026-02-04 16:56:26] [MENU] Sending menu JSON to uosc: {"title":"Medios Macina","items":[{"title":"Load URL","value":"script-message medios-load-url"},{"value":"script-binding medios-info","title":"Get Metadata","hint":"Ctrl+i"},{"value":"script-binding m...
[2026-02-04 16:56:26] [MENU] Menu command sent successfully
[2026-02-04 16:56:27] medios-load-url handler called
[2026-02-04 16:56:27] medios-load-url: closing main menu before opening Load URL prompt
[2026-02-04 16:56:27] open_load_url_prompt called
[2026-02-04 16:56:27] open_load_url_prompt: sending menu to uosc
[2026-02-04 16:56:33] [LOAD-URL] Event handler called with: {"type":"search","query":"https://www.youtube.com/watch?v=3IpPonmYx3g","menu_id":"{root}"}
[2026-02-04 16:56:33] [LOAD-URL] Parsed event: type=search, query=https://www.youtube.com/watch?v=3IpPonmYx3g
[2026-02-04 16:56:33] [LOAD-URL] Trimmed URL: "https://www.youtube.com/watch?v=3IpPonmYx3g"
[2026-02-04 16:56:33] [INFO] Load URL started: https://www.youtube.com/watch?v=3IpPonmYx3g
[2026-02-04 16:56:33] [LOAD-URL] Starting to load: https://www.youtube.com/watch?v=3IpPonmYx3g
[2026-02-04 16:56:33] [LOAD-URL] Checking if URL can be loaded directly: false
[2026-02-04 16:56:33] [LOAD-URL] URL requires pipeline helper for processing
[2026-02-04 16:56:33] [LOAD-URL] Pipeline helper ready: true
[2026-02-04 16:56:33] [LOAD-URL] Sending to pipeline: .mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[2026-02-04 16:56:33] [LOAD-URL] Pipeline helper ready: true
[2026-02-04 16:56:33] M.run_pipeline called with cmd: .mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[2026-02-04 16:56:34] ipc-async: send request id=22550-814785 cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play
[2026-02-04 16:56:35] [LOAD-URL] Event handler called with: {"type":"close"}
[2026-02-04 16:56:35] [LOAD-URL] Parsed event: type=close, query=nil
[2026-02-04 16:56:35] [LOAD-URL] Event type is not search: close
[2026-02-04 16:56:35] [LOAD-URL] Closing menu due to type mismatch
[2026-02-04 16:56:38] [WARN] Load URL still processing after 5 seconds
[2026-02-04 16:56:38] [LOAD-URL] Timeout message shown (helper still processing)
[2026-02-04 16:56:44] M.run_pipeline callback fired: resp=nil, err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[2026-02-04 16:56:44] pipeline failed cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[2026-02-04 16:56:44] [LOAD-URL] Pipeline callback received: resp=nil, err=timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[2026-02-04 16:56:44] [LOAD-URL] Pipeline error: timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[2026-02-04 16:56:44] [ERROR] Load URL pipeline failed: timeout waiting response (cmd=.mpv -url "https://www.youtube.com/watch?v=3IpPonmYx3g" -play)
[2026-02-04 16:56:44] [LOAD-URL] Closing menu
[2026-02-04 16:56:44] [LOAD-URL] Sending close-menu command to UOSC
[2026-02-04 16:56:45] ipc-async: send request id=34082-728967 op=ytdlp-formats