diff --git a/API/data/alldebrid.json b/API/data/alldebrid.json index b0946ab..1526c78 100644 --- a/API/data/alldebrid.json +++ b/API/data/alldebrid.json @@ -353,7 +353,7 @@ "filedot\\.(xyz|to|top)/([0-9a-zA-Z]{12})" ], "regexp": "filedot\\.(xyz|to|top)/([0-9a-zA-Z]{12})", - "status": false + "status": true }, "filefactory": { "name": "filefactory", diff --git a/MPV/LUA/main.lua b/MPV/LUA/main.lua index 6070cd4..91302e4 100644 --- a/MPV/LUA/main.lua +++ b/MPV/LUA/main.lua @@ -145,6 +145,81 @@ end _lua_log('medeia lua loaded version=' .. tostring(MEDEIA_LUA_VERSION) .. ' script=' .. tostring(mp.get_script_name())) +-- Log to database (logs.db) for centralized error/message tracking +-- This ensures all OSD messages and errors are persisted for debugging +local function _log_to_db(level, message) + message = tostring(message or ''):gsub('"', '\\"') + level = tostring(level or 'INFO'):upper() + + -- Find the repo root by looking for CLI.py upwards from script directory + local repo_root = '' + do + local script_dir = mp.get_script_directory() or utils.getcwd() or '' + if script_dir ~= '' then + local function find_up(start_dir, relative_path, max_levels) + local d = start_dir + local levels = max_levels or 6 + for _ = 0, levels do + if d and d ~= '' then + local candidate = d .. '/' .. relative_path + if utils.file_info(candidate) then + return candidate + end + end + local parent = d and d:match('(.*)[/\\]') or nil + if not parent or parent == d or parent == '' then + break + end + d = parent + end + return nil + end + local cli = find_up(script_dir, 'CLI.py', 8) + if cli and cli ~= '' then + repo_root = cli:match('(.*)[/\\]') or '' + end + end + end + + if repo_root == '' then + return -- Can't find repo root, skip logging to database + end + + -- Escape paths for Python subprocess + repo_root = repo_root:gsub('\\', '/') + + -- Use Python to write to the database since Lua can't easily access sqlite3 + -- We use a subprocess call with minimal Python to insert into logs.db + local python = (opts and opts.python_path) and tostring(opts.python_path) or 'python' + local db_path = repo_root .. '/logs.db' + + local script = string.format( + "import sqlite3,os;p='%s';c=sqlite3.connect(p) if os.path.exists(p) else None;c and (c.execute('INSERT INTO logs (level,module,message) VALUES (?,?,?)',('%s','mpv','%s')),c.commit(),c.close())", + db_path:gsub('\\', '/'), + level, + message + ) + + pcall(function() + mp.command_native_async({ name = 'subprocess', args = { python, '-c', script }, cwd = nil }, function() end) + end) +end + +-- Combined log: to file + database (for persistence and debugging) +local function _log_all(level, text) + if not text or text == '' then + return + end + level = tostring(level or 'INFO'):upper() + text = tostring(text) + + -- Log to file + _lua_log('[' .. level .. '] ' .. text) + + -- Log to database (async, non-blocking) + _log_to_db(level, text) +end + local function ensure_uosc_loaded() if _uosc_loaded or _is_script_loaded('uosc') then _uosc_loaded = true @@ -2317,8 +2392,9 @@ function M.run_pipeline(pipeline_cmd, seeds, cb) end ensure_mpv_ipc_server() - -- Use longer timeout for .mpv -url commands since they may involve downloading - local timeout_seconds = pipeline_cmd:match('%.mpv%s+%-url') and 120 or 30 + -- Use shorter timeout for .mpv -url commands since they just queue the URL (non-blocking) + -- The actual URL resolution happens asynchronously in MPV itself + local timeout_seconds = pipeline_cmd:match('%.mpv%s+%-url') and 10 or 30 _run_pipeline_request_async(pipeline_cmd, seeds, timeout_seconds, function(resp, err) _lua_log('M.run_pipeline callback fired: resp=' .. tostring(resp) .. ', err=' .. tostring(err)) if resp and resp.success then @@ -2776,6 +2852,7 @@ mp.register_script_message('medios-load-url-event', function(json) _lua_log('[LOAD-URL] Trimmed URL: "' .. url .. '"') if url == '' then _lua_log('[LOAD-URL] URL is empty') + _log_all('ERROR', 'Load URL failed: URL is empty') mp.osd_message('URL is empty', 2) if ensure_uosc_loaded() then _lua_log('[LOAD-URL] Closing menu due to empty URL') @@ -2785,6 +2862,7 @@ mp.register_script_message('medios-load-url-event', function(json) end mp.osd_message('Loading URL...', 1) + _log_all('INFO', 'Load URL started: ' .. url) _lua_log('[LOAD-URL] Starting to load: ' .. url) local function close_menu() @@ -2806,11 +2884,13 @@ mp.register_script_message('medios-load-url-event', function(json) local ok_load = pcall(mp.commandv, 'loadfile', url, 'replace') if ok_load then _lua_log('[LOAD-URL] Direct loadfile command sent successfully') + _log_all('INFO', 'Load URL succeeded via direct load') mp.osd_message('URL loaded', 2) close_menu() return else _lua_log('[LOAD-URL] Direct loadfile command failed') + _log_all('ERROR', 'Load URL failed: direct loadfile command failed') mp.osd_message('Load URL failed (direct)', 3) close_menu() return @@ -2824,24 +2904,57 @@ mp.register_script_message('medios-load-url-event', function(json) _lua_log('[LOAD-URL] Pipeline helper ready: ' .. tostring(helper_ready)) if not helper_ready then - _lua_log('[LOAD-URL] Pipeline helper not available') - mp.osd_message('Pipeline helper not running (try menu again)', 3) - close_menu() + _lua_log('[LOAD-URL] Pipeline helper not available, attempting to start...') + _log_all('WARN', 'Pipeline helper not running, attempting auto-start') + mp.osd_message('Starting pipeline helper...', 2) + + -- Attempt to start the helper asynchronously + attempt_start_pipeline_helper_async(function(success) + if success then + _lua_log('[LOAD-URL] Helper started successfully, retry Load URL from menu') + _log_all('INFO', 'Pipeline helper started successfully') + mp.osd_message('Helper started! Try Load URL again', 2) + else + _lua_log('[LOAD-URL] Failed to start helper') + _log_all('ERROR', 'Failed to start pipeline helper') + mp.osd_message('Could not start pipeline helper', 3) + end + close_menu() + end) return end -- Use pipeline to download/prepare the URL local pipeline_cmd = '.mpv -url ' .. quote_pipeline_arg(url) .. ' -play' _lua_log('[LOAD-URL] Sending to pipeline: ' .. pipeline_cmd) + _lua_log('[LOAD-URL] Pipeline helper ready: ' .. tostring(_is_pipeline_helper_ready())) + + -- Add a timeout message after a delay to give user feedback + local timeout_timer = nil + timeout_timer = mp.add_timeout(5, function() + if timeout_timer then + mp.osd_message('Still loading... (helper may be resolving URL)', 2) + _log_all('WARN', 'Load URL still processing after 5 seconds') + _lua_log('[LOAD-URL] Timeout message shown (helper still processing)') + end + end) + M.run_pipeline(pipeline_cmd, nil, function(resp, err) + if timeout_timer then + timeout_timer:kill() + timeout_timer = nil + end + _lua_log('[LOAD-URL] Pipeline callback received: resp=' .. tostring(resp) .. ', err=' .. tostring(err)) if err then _lua_log('[LOAD-URL] Pipeline error: ' .. tostring(err)) + _log_all('ERROR', 'Load URL pipeline failed: ' .. tostring(err)) mp.osd_message('Load URL failed: ' .. tostring(err), 3) close_menu() return end _lua_log('[LOAD-URL] URL loaded successfully') + _log_all('INFO', 'Load URL succeeded') mp.osd_message('URL loaded', 2) close_menu() end) diff --git a/cmdnat/pipe.py b/cmdnat/pipe.py index 7e5e981..1c32c75 100644 --- a/cmdnat/pipe.py +++ b/cmdnat/pipe.py @@ -1981,6 +1981,29 @@ def _run(result: Any, args: Sequence[str], config: Dict[str, Any]) -> int: "If you need full [main2] logs, restart mpv so it starts with --log-file." ) + # Print database logs for mpv module (helper output) + try: + import sqlite3 + log_db_path = str((Path(__file__).resolve().parent.parent / "logs.db")) + conn = sqlite3.connect(log_db_path, timeout=5.0) + cur = conn.cursor() + cur.execute( + "SELECT level, module, message FROM logs WHERE module = 'mpv' ORDER BY timestamp DESC LIMIT 200" + ) + mpv_logs = cur.fetchall() + cur.close() + conn.close() + + print("Helper logs from database (mpv module, most recent first):") + if mpv_logs: + for level, module, message in mpv_logs: + print(f"[{level}] {message}") + else: + print("(no helper logs found)") + except Exception as e: + debug(f"Could not fetch database logs: {e}") + pass + # Also print the helper log tail (this captures Python helper output that won't # necessarily show up in MPV's own log-file). try: diff --git a/logs.db-shm b/logs.db-shm new file mode 100644 index 0000000..51f1fcb Binary files /dev/null and b/logs.db-shm differ diff --git a/logs.db-wal b/logs.db-wal new file mode 100644 index 0000000..86a705e Binary files /dev/null and b/logs.db-wal differ diff --git a/mpv_logs_with_db.txt b/mpv_logs_with_db.txt new file mode 100644 index 0000000..87e29d3 --- /dev/null +++ b/mpv_logs_with_db.txt @@ -0,0 +1,500 @@ +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