From 1e0000ae19e55d6d9519a99b5348ef14c7570863 Mon Sep 17 00:00:00 2001 From: Nose Date: Mon, 2 Feb 2026 19:49:07 -0800 Subject: [PATCH] ff --- API/HTTP.py | 2 +- MPV/lyric.py | 79 +--- MPV/pipeline_helper.py | 17 +- Provider/podcastindex.py | 20 +- SYS/pipeline.py | 39 +- SYS/result_table.py | 76 +++- SYS/utils.py | 26 ++ cmdlet/archive_file.py | 14 +- cmdlet/get_metadata.py | 80 +++- cmdlet/get_tag.py | 20 +- cmdlet/search_file.py | 71 +++- cmdnat/matrix.py | 14 +- logs/log_fallback.txt | 827 --------------------------------------- 13 files changed, 297 insertions(+), 988 deletions(-) delete mode 100644 logs/log_fallback.txt diff --git a/API/HTTP.py b/API/HTTP.py index 2af636b..83e05e1 100644 --- a/API/HTTP.py +++ b/API/HTTP.py @@ -1156,7 +1156,7 @@ class AsyncHTTPClient: if 400 <= e.response.status_code < 500: try: response_text = e.response.text[:500] - except: + except Exception: response_text = "" logger.error( f"HTTP {e.response.status_code} from {url}: {response_text}" diff --git a/MPV/lyric.py b/MPV/lyric.py index e337d97..05cba27 100644 --- a/MPV/lyric.py +++ b/MPV/lyric.py @@ -56,7 +56,7 @@ _LYRIC_VISIBLE_PROP = "user-data/medeia-lyric-visible" # Optional overrides set by the playlist controller (.pipe/.mpv) so the lyric # helper can resolve notes even when the local file path cannot be mapped back -# to a store via the store DB (common for Folder stores). +# to a store via the store DB. _ITEM_STORE_PROP = "user-data/medeia-item-store" _ITEM_HASH_PROP = "user-data/medeia-item-hash" @@ -804,11 +804,7 @@ def _resolve_store_backend_for_target( except Exception: return None, None - # Prefer the inferred Folder store (fast), but still validate via get_file(). - preferred = _infer_store_for_target(target=target, config=config) - if preferred and preferred in backend_names: - backend_names.remove(preferred) - backend_names.insert(0, preferred) + for name in backend_names: try: @@ -842,80 +838,9 @@ def _resolve_store_backend_for_target( return name, backend - # Fallback for Folder stores: - # If the mpv target is inside a configured Folder store root and the filename - # is hash-named, accept the inferred store even if the store DB doesn't map - # hash->path (e.g. DB missing entry, external copy, etc.). - try: - inferred = _infer_store_for_target(target=target, config=config) - if inferred and inferred in backend_names: - backend = reg[inferred] - if type(backend).__name__ == "Folder": - p = Path(target) - stem = str(p.stem or "").strip().lower() - if stem and stem == str(file_hash or "").strip().lower(): - return inferred, backend - except Exception: - pass - return None, None -def _infer_store_for_target(*, target: str, config: dict) -> Optional[str]: - """Infer store name from the current mpv target (local path under a folder root). - - Note: URLs/streams are intentionally not mapped to stores for lyrics. - """ - if isinstance(target, str) and _is_stream_target(target): - return None - - try: - from Store import Store as StoreRegistry - - reg = StoreRegistry(config, suppress_debug=True) - backends = [(name, reg[name]) for name in reg.list_backends()] - except Exception: - backends = [] - - # Local file path: choose the deepest Folder root that contains it. - try: - p = Path(target) - if not p.exists() or not p.is_file(): - return None - p_str = str(p.resolve()).lower() - except Exception: - return None - - best: Optional[str] = None - best_len = -1 - for name, backend in backends: - if type(backend).__name__ != "Folder": - continue - root = None - try: - root = ( - getattr(backend, - "_location", - None) or getattr(backend, - "location", lambda: None)() - ) - except Exception: - root = None - if not root: - continue - try: - root_path = Path(str(root)).expanduser().resolve() - root_str = str(root_path).lower().rstrip("\\/") - except Exception: - continue - - if p_str.startswith(root_str) and len(root_str) > best_len: - best = name - best_len = len(root_str) - - return best - - def _infer_hash_for_target(target: str) -> Optional[str]: """Infer SHA256 hash from Hydrus URL query, hash-named local files, or by hashing local file content.""" h = _extract_hash_from_target(target) diff --git a/MPV/pipeline_helper.py b/MPV/pipeline_helper.py index 8ded2c2..96d1268 100644 --- a/MPV/pipeline_helper.py +++ b/MPV/pipeline_helper.py @@ -64,6 +64,7 @@ if _ROOT not in sys.path: from MPV.mpv_ipc import MPVIPCClient # noqa: E402 from SYS.config import load_config # noqa: E402 from SYS.logger import set_debug, debug, set_thread_stream # noqa: E402 +from SYS.utils import format_bytes # noqa: E402 REQUEST_PROP = "user-data/medeia-pipeline-request" RESPONSE_PROP = "user-data/medeia-pipeline-response" @@ -395,20 +396,8 @@ def _run_op(op: str, data: Any) -> Dict[str, Any]: ydl_opts["cookiefile"] = cookiefile def _format_bytes(n: Any) -> str: - try: - v = float(n) - except Exception: - return "" - if v <= 0: - return "" - units = ["B", "KB", "MB", "GB", "TB"] - i = 0 - while v >= 1024 and i < len(units) - 1: - v /= 1024.0 - i += 1 - if i == 0: - return f"{int(v)} {units[i]}" - return f"{v:.1f} {units[i]}" + """Format bytes using centralized utility.""" + return format_bytes(n) with yt_dlp.YoutubeDL(ydl_opts) as ydl: # type: ignore[attr-defined] info = ydl.extract_info(url, download=False) diff --git a/Provider/podcastindex.py b/Provider/podcastindex.py index ba1f6e8..909cc7c 100644 --- a/Provider/podcastindex.py +++ b/Provider/podcastindex.py @@ -7,6 +7,7 @@ from typing import Any, Dict, List, Optional, Tuple from ProviderCore.base import Provider, SearchResult from SYS.logger import log +from SYS.utils import format_bytes def _get_podcastindex_credentials(config: Dict[str, Any]) -> Tuple[str, str]: @@ -79,23 +80,8 @@ class PodcastIndex(Provider): @staticmethod def _format_bytes(value: Any) -> str: - try: - n = int(value) - except Exception: - return "" - if n <= 0: - return "" - units = ["B", "KB", "MB", "GB", "TB"] - size = float(n) - unit = units[0] - for u in units: - unit = u - if size < 1024.0 or u == units[-1]: - break - size /= 1024.0 - if unit == "B": - return f"{int(size)}{unit}" - return f"{size:.1f}{unit}" + """Format bytes using centralized utility.""" + return format_bytes(value) @staticmethod def _format_date_from_epoch(value: Any) -> str: diff --git a/SYS/pipeline.py b/SYS/pipeline.py index 1394a1f..abe881f 100644 --- a/SYS/pipeline.py +++ b/SYS/pipeline.py @@ -584,8 +584,20 @@ def set_last_result_table( items: Optional[List[Any]] = None, subject: Optional[Any] = None ) -> None: - """ - Store the last result table and items for @ selection syntax. + """Store the last result table and items for @ selection syntax. + + Persists result table and items across command invocations, enabling + subsequent commands to reference and operate on previous results using @N syntax. + + Example: + search-file hash:<...> # Returns table with 3 results + @1 | get-metadata # Gets metadata for result #1 + @2 | add-tag foo # Adds tag to result #2 + + Args: + result_table: Table object with results (can be None to clear) + items: List of item objects corresponding to table rows + subject: Optional context object (first item or full list) """ state = _get_pipeline_state() @@ -662,6 +674,16 @@ def set_last_result_table_overlay( Used by action cmdlets (get-metadata, get-tag, get-url) to display detail panels or filtered results without disrupting the primary search-result history. + + Difference from set_last_result_table(): + - Overlay tables are transient (in-process memory only) + - Don't persist across command invocations + - Used for "live" displays that shouldn't be part of @N selection + + Args: + result_table: Table object with transient results + items: List of item objects (not persisted) + subject: Optional context object """ state = _get_pipeline_state() state.display_table = result_table @@ -833,8 +855,17 @@ def get_last_result_table() -> Optional[Any]: def get_last_result_items() -> List[Any]: - """ - Get the items available for @N selection. + """Get the items available for @N selection in current pipeline context. + + Returns items in priority order: + 1. Display items (from get-tag, get-metadata, etc.) if display table is selectable + 2. Last result items (from search-file, etc.) if last result table is selectable + 3. Empty list if no selectable tables available + + Used to resolve @1, @2, etc. in commands. + + Returns: + List of items that can be selected via @N syntax """ state = _get_pipeline_state() # Prioritize items from display commands (get-tag, delete-tag, etc.) diff --git a/SYS/result_table.py b/SYS/result_table.py index abe3ed5..8c0a20d 100644 --- a/SYS/result_table.py +++ b/SYS/result_table.py @@ -136,7 +136,19 @@ def _get_first_dict_value(data: Dict[str, Any], keys: List[str]) -> Any: def _as_dict(item: Any) -> Optional[Dict[str, Any]]: - if isinstance(item, dict): + """Convert any object to dictionary representation. + + Handles: + - Dict objects (returned as-is) + - Objects with __dict__ attribute (converted to dict) + - None or conversion failures (returns None) + + Args: + item: Object to convert (dict, dataclass, object, etc.) + + Returns: + Dictionary representation or None if conversion fails + """ return item try: if hasattr(item, "__dict__"): @@ -148,7 +160,17 @@ def _as_dict(item: Any) -> Optional[Dict[str, Any]]: def extract_store_value(item: Any) -> str: - data = _as_dict(item) or {} + """Extract storage backend name from item. + + Searches item for store identifier using multiple field names: + store, table, source, storage (legacy). + + Args: + item: Object or dict with store information + + Returns: + Store name as string (e.g., "hydrus", "local", "") if not found + """ store = _get_first_dict_value( data, ["store", @@ -1959,7 +1981,33 @@ def format_result(result: Any, title: str = "") -> str: def extract_item_metadata(item: Any) -> Dict[str, Any]: """Extract a comprehensive set of metadata from an item for the ItemDetailView. - Now supports SYS.result_table_api.ResultModel as a first-class input. + Converts items (ResultModel, dicts, objects) into normalized metadata dict. + Extracts all relevant fields for display: Title, Hash, Store, Path, Ext, Size, + Duration, URL, Relations, Tags. + + Optimization: + - Calls _as_dict() only once and reuses throughout + - Handles both ResultModel objects and legacy dicts/objects + + Example output: + { + "Title": "video.mp4", + "Hash": "abc123def456...", + "Store": "hydrus", + "Path": "/mnt/media/video.mp4", + "Ext": "mp4", + "Size": "1.2 GB", + "Duration": "1h23m", + "Url": "https://example.com/video.mp4", + "Relations": , + "Tags": "movie, comedy" + } + + Args: + item: Object to extract metadata from (ResultModel, dict, or any object) + + Returns: + Dictionary with standardized metadata fields (empty dict if None input) """ if item is None: return {} @@ -1996,13 +2044,15 @@ def extract_item_metadata(item: Any) -> Dict[str, Any]: return out # Fallback to existing extraction logic for legacy objects/dicts + # Convert once and reuse throughout to avoid repeated _as_dict() calls + data = _as_dict(item) or {} + # Use existing extractors from match-standard result table columns title = extract_title_value(item) if title: out["Title"] = title else: # Fallback for raw dicts - data = _as_dict(item) or {} t = data.get("title") or data.get("name") or data.get("TITLE") if t: out["Title"] = t @@ -2013,7 +2063,6 @@ def extract_item_metadata(item: Any) -> Dict[str, Any]: if store: out["Store"] = store # Path/Target - data = _as_dict(item) or {} path = data.get("path") or data.get("target") or data.get("filename") if path: out["Path"] = path @@ -2066,6 +2115,23 @@ class ItemDetailView(Table): This is used for 'get-tag', 'get-url' and similar cmdlets where we want to contextually show what is being operated on (the main item) along with the selection list. + + Display structure: + ┌─ Item Details Panel ─────────────────────────────┐ + │ Title: video.mp4 │ + │ Hash: abc123def456789... │ + │ Store: hydrus │ + │ Path: /media/video.mp4 │ + │ Ext: mp4 │ + │ Url: https://example.com/video.mp4 │ + └────────────────────────────────────────────────────┘ + + # TAGS Value + 1 .jpg + 2 .png + 3 .webp + + Used by action cmdlets that operate on an item and show its related details. """ def __init__( diff --git a/SYS/utils.py b/SYS/utils.py index f775e7f..7dfd6cb 100644 --- a/SYS/utils.py +++ b/SYS/utils.py @@ -552,6 +552,32 @@ def add_direct_link_to_result( setattr(result, "original_link", original_link) +def extract_hydrus_hash_from_url(url: str) -> str | None: + """Extract SHA256 hash from Hydrus API URL. + + Handles URLs like: + - http://localhost:45869/get_files/file?hash=abc123... + - URLs with &hash=abc123... + + Args: + url: URL string to extract hash from + + Returns: + Hash hex string (lowercase, 64 chars) if valid SHA256, None otherwise + """ + try: + import re + match = re.search(r"[?&]hash=([0-9a-fA-F]+)", str(url or "")) + if match: + hash_hex = match.group(1).strip().lower() + # Validate SHA256 (exactly 64 hex chars) + if re.fullmatch(r"[0-9a-f]{64}", hash_hex): + return hash_hex + except Exception: + pass + return None + + # ============================================================================ # URL Policy Resolution - Consolidated from url_parser.py # ============================================================================ diff --git a/cmdlet/archive_file.py b/cmdlet/archive_file.py index 95a1d3a..dfdeb54 100644 --- a/cmdlet/archive_file.py +++ b/cmdlet/archive_file.py @@ -13,6 +13,7 @@ from typing import Any, Dict, List, Sequence, Set from urllib.parse import parse_qs, urlparse from SYS.logger import log +from SYS.utils import extract_hydrus_hash_from_url from SYS import pipeline as ctx from SYS.config import resolve_output_dir @@ -64,17 +65,8 @@ def _extract_url(item: Any) -> str: def _extract_hash_from_hydrus_file_url(url: str) -> str: - try: - parsed = urlparse(str(url)) - if not (parsed.path or "").endswith("/get_files/file"): - return "" - qs = parse_qs(parsed.query or "") - h = (qs.get("hash") or [""])[0] - if isinstance(h, str) and _SHA256_RE.fullmatch(h.strip()): - return h.strip().lower() - except Exception: - pass - return "" + """Extract hash from Hydrus URL using centralized utility.""" + return extract_hydrus_hash_from_url(url) or "" def _hydrus_instance_names(config: Dict[str, Any]) -> Set[str]: diff --git a/cmdlet/get_metadata.py b/cmdlet/get_metadata.py index 1a7cd8f..738e24b 100644 --- a/cmdlet/get_metadata.py +++ b/cmdlet/get_metadata.py @@ -43,7 +43,18 @@ class Get_Metadata(Cmdlet): @staticmethod def _extract_imported_ts(meta: Dict[str, Any]) -> Optional[int]: - """Extract an imported timestamp from metadata if available.""" + """Extract an imported timestamp from metadata if available. + + Attempts to parse imported timestamp from metadata dict in multiple formats: + - Numeric Unix timestamp (int/float) + - ISO format string (e.g., "2024-01-15T10:30:00") + + Args: + meta: Metadata dictionary from backend (e.g., from get_metadata()) + + Returns: + Unix timestamp as integer if found, None otherwise + """ if not isinstance(meta, dict): return None @@ -65,7 +76,17 @@ class Get_Metadata(Cmdlet): @staticmethod def _format_imported(ts: Optional[int]) -> str: - """Format timestamp as readable string.""" + """Format Unix timestamp as human-readable date string (UTC). + + Converts Unix timestamp to YYYY-MM-DD HH:MM:SS format. + Used for displaying file import dates to users. + + Args: + ts: Unix timestamp (integer) or None + + Returns: + Formatted date string (e.g., "2024-01-15 10:30:00") or empty string if invalid + """ if not ts: return "" try: @@ -91,7 +112,30 @@ class Get_Metadata(Cmdlet): ext: Optional[str] = None, ) -> Dict[str, Any]: - """Build a table row dict with metadata fields.""" + """Build a normalized metadata row dict for display and piping. + + Converts raw metadata fields into a standardized row format suitable for: + - Display in result tables + - Piping to downstream cmdlets + - JSON serialization + + Args: + title: File or resource title + store: Backend store name (e.g., "hydrus", "local") + path: File path or resource identifier + mime: MIME type (e.g., "image/jpeg", "video/mp4") + size_bytes: File size in bytes + dur_seconds: Duration in seconds (for video/audio) + imported_ts: Unix timestamp when item was imported + url: List of known URLs associated with file + hash_value: File hash (SHA256 or other) + pages: Number of pages (for PDFs) + tag: List of tags applied to file + ext: File extension (e.g., "jpg", "mp4") + + Returns: + Dictionary with normalized metadata fields and display columns + """ size_mb = None size_int: Optional[int] = None if size_bytes is not None: @@ -151,7 +195,15 @@ class Get_Metadata(Cmdlet): @staticmethod def _add_table_body_row(table: Table, row: Dict[str, Any]) -> None: - """Add a single row to the ResultTable using the prepared columns.""" + """Add a single metadata row to the result table. + + Extracts column values from row dict and adds to result table using + standard column ordering (Hash, MIME, Size, Duration/Pages). + + Args: + table: Result table to add row to + row: Metadata row dict (from _build_table_row) + """ columns = row.get("columns") if isinstance(row, dict) else None lookup: Dict[str, Any] = {} @@ -173,7 +225,25 @@ class Get_Metadata(Cmdlet): row_obj.add_column("Duration(s)", "") def run(self, result: Any, args: Sequence[str], config: Dict[str, Any]) -> int: - """Main execution entry point.""" + """Execute get-metadata cmdlet - retrieve and display file metadata. + + Queries a storage backend (Hydrus, local, etc.) for file metadata using hash. + Extracts tags embedded in metadata response (avoiding duplicate API calls). + Displays metadata in rich detail panel and result table. + Allows piping (@N) to other cmdlets for chaining operations. + + Optimizations: + - Extracts tags from metadata response (no separate get_tag() call) + - Single HTTP request to backends per file + + Args: + result: Piped input (dict with optional hash/store/title/tag fields) + args: Command line arguments ([-query "hash:..."] [-store backend]) + config: Application configuration dict + + Returns: + 0 on success, 1 on error (no metadata found, backend unavailable, etc.) + """ # Parse arguments parsed = parse_cmdlet_args(args, self) diff --git a/cmdlet/get_tag.py b/cmdlet/get_tag.py index 7b49e9e..ef2b24d 100644 --- a/cmdlet/get_tag.py +++ b/cmdlet/get_tag.py @@ -318,8 +318,24 @@ def _emit_tags_as_table( ) -> None: """Emit tags as TagItem objects and display via ResultTable. - This replaces _print_tag_list to make tags pipe-able. - Stores the table via ctx.set_last_result_table_overlay (or ctx.set_last_result_table) for downstream @ selection. + Displays tags in a rich detail panel with file context (hash, title, URL, etc). + Creates a table of individual tag items to allow selection and downstream piping. + Preserves all metadata from subject (URLs, extensions, etc.) through to display. + + Makes tags @-selectable via ctx.set_last_result_table() for chaining: + - get-tag @1 | delete-tag (remove a specific tag) + - get-tag @2 | add-url (add URL to tagged file) + + Args: + tags_list: List of tag strings to display + file_hash: SHA256 hash of file + store: Backend name (e.g., "hydrus", "local", "url") + service_name: Tag service name (if from Hydrus) + config: Application configuration + item_title: Optional file title to display + path: Optional file path + subject: Full context object (should preserve original metadata) + quiet: If True, don't display (emit-only mode) """ from SYS.result_table import ItemDetailView, extract_item_metadata diff --git a/cmdlet/search_file.py b/cmdlet/search_file.py index ae6974f..2cc0e55 100644 --- a/cmdlet/search_file.py +++ b/cmdlet/search_file.py @@ -389,7 +389,27 @@ class search_file(Cmdlet): # --- Execution ------------------------------------------------------ def run(self, result: Any, args: Sequence[str], config: Dict[str, Any]) -> int: - """Search storage backends for files.""" + """Search storage backends for files by various criteria. + + Supports searching by: + - Hash (-query "hash:...") + - Title (-query "title:...") + - Tag (-query "tag:...") + - URL (-query "url:...") + - Other backend-specific fields + + Optimizations: + - Extracts tags from metadata response (avoids duplicate API calls) + - Only calls get_tag() separately for backends that don't include tags + + Args: + result: Piped input (typically empty for new search) + args: Search criteria and options + config: Application configuration + + Returns: + 0 on success, 1 on error + """ if should_show_help(args): log(f"Cmdlet: {self.name}\nSummary: {self.summary}\nUsage: {self.usage}") return 0 @@ -698,20 +718,43 @@ class search_file(Cmdlet): except Exception: meta_obj = {} + # Extract tags from metadata response instead of separate get_tag() call + # Metadata already includes tags if fetched with include_service_keys_to_tags=True tags_list: List[str] = [] - try: - tag_result = resolved_backend.get_tag(h) - if isinstance(tag_result, tuple) and tag_result: - maybe_tags = tag_result[0] - else: - maybe_tags = tag_result - if isinstance(maybe_tags, list): - tags_list = [ - str(t).strip() for t in maybe_tags - if isinstance(t, str) and str(t).strip() - ] - except Exception: - tags_list = [] + + # First try to extract from metadata tags dict + metadata_tags = meta_obj.get("tags") + if isinstance(metadata_tags, dict): + for service_data in metadata_tags.values(): + if isinstance(service_data, dict): + display_tags = service_data.get("display_tags", {}) + if isinstance(display_tags, dict): + for tag_list in display_tags.values(): + if isinstance(tag_list, list): + tags_list = [ + str(t).strip() for t in tag_list + if isinstance(t, str) and str(t).strip() + ] + break + if tags_list: + break + + # Fallback: if metadata didn't include tags, call get_tag() separately + # (This maintains compatibility with backends that don't include tags in metadata) + if not tags_list: + try: + tag_result = resolved_backend.get_tag(h) + if isinstance(tag_result, tuple) and tag_result: + maybe_tags = tag_result[0] + else: + maybe_tags = tag_result + if isinstance(maybe_tags, list): + tags_list = [ + str(t).strip() for t in maybe_tags + if isinstance(t, str) and str(t).strip() + ] + except Exception: + tags_list = [] title_from_tag: Optional[str] = None try: diff --git a/cmdnat/matrix.py b/cmdnat/matrix.py index d33ae3e..c72a2e9 100644 --- a/cmdnat/matrix.py +++ b/cmdnat/matrix.py @@ -12,6 +12,7 @@ from cmdlet._shared import Cmdlet, CmdletArg from SYS.config import load_config, save_config from SYS.logger import log, debug from SYS.result_table import Table +from SYS.utils import extract_hydrus_hash_from_url from SYS import pipeline as ctx _MATRIX_PENDING_ITEMS_KEY = "matrix_pending_items" @@ -543,17 +544,8 @@ def _extract_sha256_hex(item: Any) -> Optional[str]: def _extract_hash_from_hydrus_file_url(url: str) -> Optional[str]: - try: - parsed = urlparse(url) - if not (parsed.path or "").endswith("/get_files/file"): - return None - qs = parse_qs(parsed.query or "") - h = (qs.get("hash") or [None])[0] - if isinstance(h, str) and _SHA256_RE.fullmatch(h.strip()): - return h.strip().lower() - except Exception: - pass - return None + """Extract hash from Hydrus URL using centralized utility.""" + return extract_hydrus_hash_from_url(url) def _maybe_download_hydrus_file(item: Any, diff --git a/logs/log_fallback.txt b/logs/log_fallback.txt deleted file mode 100644 index 959746b..0000000 --- a/logs/log_fallback.txt +++ /dev/null @@ -1,827 +0,0 @@ -2026-01-30T22:25:43.107858Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-30T22:25:59.982134Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-30T22:26:16.885919Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-30T22:26:33.793917Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: * -2026-01-30T22:26:50.694904Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:27:07.572758Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:27:24.447150Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:27:41.284396Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:27:58.181684Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 12 result(s) -2026-01-30T22:28:15.009135Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 12 result(s) -2026-01-30T22:28:31.863769Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-30T22:28:48.711179Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: * -2026-01-30T22:29:05.576668Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:29:22.412766Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:29:39.282886Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:29:56.194705Z [DEBUG] logger.debug: DEBUG: -2026-01-30T22:30:13.080953Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 88 result(s) -2026-01-30T22:30:30.070854Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 88 result(s) -2026-01-30T23:06:37.123461Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-30T23:06:53.988994Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-30T23:07:10.875554Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-30T23:07:27.718017Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-30T23:07:44.568972Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: * -2026-01-30T23:08:01.458991Z [DEBUG] logger.debug: DEBUG: -2026-01-30T23:08:18.408113Z [DEBUG] logger.debug: DEBUG: -2026-01-30T23:08:35.307969Z [DEBUG] logger.debug: DEBUG: -2026-01-30T23:08:52.181486Z [DEBUG] logger.debug: DEBUG: -2026-01-30T23:09:09.049140Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 12 result(s) -2026-01-30T23:09:25.957724Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 12 result(s) -2026-01-30T23:09:42.865060Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-30T23:09:59.774037Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: * -2026-01-30T23:10:16.647306Z [DEBUG] logger.debug: DEBUG: -2026-01-30T23:10:33.574417Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:32:24.014975Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:32:40.879653Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:32:57.755760Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T00:33:14.615372Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T00:33:31.464873Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: * -2026-01-31T00:33:48.313008Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:34:05.139870Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:34:22.033747Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:34:38.885457Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:34:42.661767Z [INFO] config.load_config: Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix, telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-01-31T00:30:49.598879Z -2026-01-31T00:34:43.346725Z [INFO] config.load_config: Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix, telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-01-31T00:30:49.598879Z -2026-01-31T00:34:55.774266Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 12 result(s) -2026-01-31T00:35:12.699558Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 12 result(s) -2026-01-31T00:35:29.604228Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T00:35:46.473300Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: * -2026-01-31T00:36:03.339421Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:36:20.200210Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:36:37.081839Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:36:53.926188Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:37:10.773730Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 88 result(s) -2026-01-31T00:37:27.616822Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 88 result(s) -2026-01-31T00:37:44.432249Z [DEBUG] logger.debug: DEBUG: pipe._run: received result type= repr=PipeObject(hash='4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', store='rpi', provider=None, tag=['album:xo (deluxe edition)', 'artist:elliott smith', 'disk:01', 'title:waltz #1', ' -2026-01-31T00:38:01.268863Z [DEBUG] logger.debug: DEBUG: pipe._run: attrs path=None url=http://127.0.0.1:45869/view_file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d store=rpi hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d -2026-01-31T00:38:18.109269Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T00:38:34.958760Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T00:38:51.823254Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:39:08.710952Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', store='rpi', provider=None, tag=['album:xo (deluxe edition)', 'artist:elliott smith', 'disk:01', 'title:waltz #1', ' -2026-01-31T00:39:25.587072Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=rpi, path=http://127.0.0.1:45869/view_file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d, hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d -2026-01-31T00:39:42.476553Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,waltz #1 -http://10.162.158.28:45899/get_files/file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d&store=rpi mode=append wait=True -2026-01-31T00:39:59.365491Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: None -2026-01-31T00:40:16.238575Z [DEBUG] logger.debug: DEBUG: MPV not running/died while queuing, starting MPV with remaining items: [PipeObject(hash='4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', store='rpi', provider=None, tag=['album:xo (deluxe edition)', 'artist:elliott smith', 'disk:01', 'title:waltz #1', 'track:08', 'track:23'], title='waltz #1', url='http://127.0.0.1:45869/view_file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', source_url=None, duration=None, metadata={}, warnings=[], path=None, relationships={}, is_temp=False, action=None, parent_hash=None, extra={'name': 'waltz #1', 'size': 20552524, 'size_bytes': 20552524, 'file_id': 463, 'mime': 'audio/flac', 'ext': 'flac', '_selection_args': ['-query', 'hash:4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', '-store', 'rpi'], '_selection_action': ['get-metadata', '-query', 'hash:4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', '-store', 'rpi'], 'url': ['http://127.0.0.1:45869/view_file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', 'https://0x0.st/PZB0.flac']})] -2026-01-31T00:40:33.142501Z [DEBUG] logger.debug: DEBUG: Starting MPV with cookies file: C:/Forgejo/Medios-Macina/cookies.txt -2026-01-31T00:40:50.031801Z [DEBUG] logger.debug: DEBUG: Starting MPV -2026-01-31T00:41:06.903086Z [DEBUG] logger.debug: DEBUG: Started MPV process -2026-01-31T00:41:23.797241Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-store", "rpi"], "request_id": 901} -2026-01-31T00:41:40.657620Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":901,"error":"success"} -2026-01-31T00:41:57.561420Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-hash", "4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d"], "request_id": 902} -2026-01-31T00:42:14.453061Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":902,"error":"success"} -2026-01-31T00:42:31.307040Z [DEBUG] logger.debug: DEBUG: Lyric loader started (log=C:\Forgejo\Medios-Macina\Log\medeia-mpv-lyric.log) -2026-01-31T00:42:48.162914Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T00:43:05.007375Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T00:43:21.848473Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:43:38.708444Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T00:43:55.624508Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[],"request_id":100,"error":"success"} -2026-01-31T00:44:12.533705Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d', store='rpi', provider=None, tag=['album:xo (deluxe edition)', 'artist:elliott smith', 'disk:01', 'title:waltz #1', ' -2026-01-31T00:44:29.426328Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=rpi, path=http://127.0.0.1:45869/view_file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d, hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d -2026-01-31T00:44:46.320512Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "http-header-fields", "Hydrus-Client-API-Access-Key: d701cad8f830210a81af2df30c9a7f82bb33d21130b4efe33a3feeca78be9b81"], "request_id": 199} -2026-01-31T00:45:03.194209Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":199,"error":"success"} -2026-01-31T00:45:20.089229Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "ytdl-raw-options", "cookies=C:/Forgejo/Medios-Macina/cookies.txt,add-header=Hydrus-Client-API-Access-Key: d701cad8f830210a81af2df30c9a7f82bb33d21130b4efe33a3feeca78be9b81"], "request_id": 197} -2026-01-31T00:45:37.002956Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":197,"error":"success"} -2026-01-31T00:45:53.905297Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,waltz #1 -http://10.162.158.28:45899/get_files/file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d&store=rpi mode=append wait=True -2026-01-31T00:46:10.799545Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["loadlist", "memory://#EXTM3U\n#EXTINF:-1,waltz #1\nhttp://10.162.158.28:45899/get_files/file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d&store=rpi", "append"], "request_id": 200} -2026-01-31T00:46:27.703037Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":{"playlist_entry_id":1,"num_entries":1},"request_id":200,"error":"success"} -2026-01-31T00:46:44.619721Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: {'data': {'playlist_entry_id': 1, 'num_entries': 1}, 'request_id': 200, 'error': 'success'} -2026-01-31T00:47:01.549180Z [DEBUG] logger.debug: DEBUG: Queued: waltz #1 -2026-01-31T00:47:18.449027Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["playlist-play-index", 0], "request_id": 102} -2026-01-31T00:47:35.360447Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":null,"request_id":102,"error":"success"} -2026-01-31T00:47:52.246497Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "pause", false], "request_id": 103} -2026-01-31T00:48:09.137209Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":103,"error":"success"} -2026-01-31T00:48:26.046181Z [DEBUG] logger.debug: DEBUG: Auto-playing first item -2026-01-31T00:48:42.940566Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T00:48:59.870292Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[{"filename":"http://10.162.158.28:45899/get_files/file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d&store=rpi","current":true,"playing":true,"title":"waltz #1","id":1,"playlist-path":"memory://#EXTM3U\n#EXTINF:-1,waltz #1\nhttp://10.162.158.28:45899/get_files/file?hash=4aff346650b5c987809396c88416b4d41acf80353482ea561d8c7d57d7d5d45d&store=rpi"}],"request_id":100,"error":"success"} -2026-01-31T00:49:16.786518Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:49:33.674815Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:49:50.646371Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:50:07.533710Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:50:24.422236Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:50:41.328178Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:50:58.257378Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:51:15.184025Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T00:51:32.098288Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T00:51:48.965799Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: * -2026-01-31T00:52:05.896072Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:52:22.802077Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:52:39.671225Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:52:56.555446Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:53:13.445790Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 12 result(s) -2026-01-31T00:53:30.319208Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 12 result(s) -2026-01-31T00:53:47.190299Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T00:54:04.062791Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: * -2026-01-31T00:54:20.959287Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:54:37.859599Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:54:54.749722Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:55:11.640889Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:55:28.548524Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 88 result(s) -2026-01-31T00:55:45.431772Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 88 result(s) -2026-01-31T00:56:02.327828Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:56:19.242933Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T00:56:36.198209Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T00:56:53.106943Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T00:57:10.017588Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: what -2026-01-31T00:57:26.920107Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:57:43.822379Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:58:00.700303Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:58:17.629824Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:58:34.565244Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:58:51.483557Z [DEBUG] logger.debug: DEBUG: -2026-01-31T00:59:08.387727Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 2 result(s) -2026-01-31T00:59:25.268672Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 2 result(s) -2026-01-31T00:59:42.168454Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T00:59:59.084790Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: what -2026-01-31T01:00:16.004188Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:00:32.899327Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:00:49.810487Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:01:06.730723Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:01:23.634024Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:01:40.542916Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:01:57.458004Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 15 result(s) -2026-01-31T01:02:14.357942Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 15 result(s) -2026-01-31T01:02:31.256461Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:02:48.153713Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:03:05.077703Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T01:03:22.004403Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T01:03:38.914311Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: what's up -2026-01-31T01:03:55.818688Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:04:12.731011Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:04:29.571244Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:04:46.467405Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:05:03.361610Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 0 result(s) -2026-01-31T01:05:20.259368Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 0 result(s) -2026-01-31T01:05:37.169588Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T01:05:54.067087Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: what's up -2026-01-31T01:06:10.994220Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:06:27.881084Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:06:44.716796Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:07:01.638186Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:07:18.536362Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 0 result(s) -2026-01-31T01:07:35.456905Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 0 result(s) -2026-01-31T01:07:52.389381Z [DEBUG] search_file.run: No results found -2026-01-31T01:08:09.329568Z [DEBUG] logger.debug: DEBUG: [search-file] Calling tidal.search(filters={}) -2026-01-31T01:08:26.280597Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:08:43.209911Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:09:00.158103Z [DEBUG] logger.debug: DEBUG: [search-file] tidal -> 25 result(s) -2026-01-31T01:09:17.094470Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-01-31T01:09:34.012103Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-01-31T01:09:50.924431Z [DEBUG] logger.debug: DEBUG: @N row 20 has no selection_action -2026-01-31T01:10:07.825208Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-url', 'tidal://track/634519'] -2026-01-31T01:10:24.759953Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T01:10:41.731285Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T01:10:58.682628Z [DEBUG] logger.debug: DEBUG: Processing URL: tidal://track/634519 -2026-01-31T01:11:15.636138Z [DEBUG] logger.debug: DEBUG: Provider tidal claimed tidal://track/634519 -2026-01-31T01:11:32.574766Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:11:49.515748Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:12:06.441198Z [DEBUG] logger.debug: DEBUG: [API.Tidal] info_resp (len=1253): {'version': '2.2', 'data': {'id': 634519, 'title': "What's Up?", 'duration': 296, 'replayGain': -7.13, 'peak': 0.977234, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '1992-10-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 3, 'volumeNumber': 1, 'version': None, 'popularity': 84, 'copyright': '℗ 1992 UMG Recordings, Inc.', 'bpm': 135, 'key': None, 'keyScale': None, 'url': 'http://www.tidal.com/track/634519', 'isrc': 'USIR19200553', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': 'PUBLIC', 'spotlighted': False, 'artist': {'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}, 'artists': [{'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}], 'album': {'id': 634516, 'title': 'Bigger, Better, Faster, More !', 'cover': '8f0a6253-153d-4049-8b1e-39d1959f50cb', 'vibrantColor': '#eae3cf', 'videoCover': None}, 'mixes': {'TRACK_MIX': '0014e63a4d993121badae9251e5a13'}}} -2026-01-31T01:12:23.357262Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:12:40.279065Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:12:57.223090Z [DEBUG] logger.debug: DEBUG: [API.Tidal] track_resp (len=752): {'version': '2.2', 'data': {'trackId': 634519, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'audioQuality': 'LOSSLESS', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': '77M6qmPIFtMMElsdireVDT8Ohxum/b5XUU0EBXEmXJY=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUtBZ0RFaVE1TVRkalptSTRaV1U1TURsbU4yVTVaalJtTUdKaU0yRmlZVEEyTW1abU5TNXRjRFEvMC5mbGFjP3Rva2VuPTE3Njk4MjMxOTN+TlRGaU9HWTNOVEF3T0RBM05XVXdPRFprTkRCbFpHSXlOamt5WVdSaE5XSXlZV1EzTkdGaFpBPT0iXX0=', 'albumReplayGain': -7.13, 'albumPeakAmplitude': 0.977234, 'trackReplayGain': -5.4, 'trackPeakAmplitude': 0.977234, 'bitDepth': 16, 'sampleRate': 44100}} -2026-01-31T01:13:14.151447Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:13:31.094418Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:13:48.013259Z [DEBUG] logger.debug: DEBUG: [API.Tidal] lyrics_resp (len=4211): {'version': '2.2', 'lyrics': {'trackId': 634519, 'lyricsProvider': 'MUSIXMATCH', 'providerCommontrackId': '10421751', 'providerLyricsId': '42557227', 'lyrics': '25 years and my life is still\nTrying to get up that great big hill of hope\nFor a destination\nI realized quickly when I knew I should\nThat the world was made up of this brotherhood of man\nFor whatever that means\n\nAnd so I cry sometimes when I\'m lying in bed\nJust to get it all out, what\'s in my head\nAnd I, I am feeling a little peculiar\nAnd so, I wake in the morning and I step outside\nAnd I take a deep breath and I get real high\nAnd I scream from the top of my lungs, "What\'s going on?"\n\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\n\nOoh, ooh, ooh\nOoh\nOoh, ooh, ooh\nOoh\n\nAnd I try, oh, my God, do I try?\nI try all the time, in this institution\nAnd I pray, oh, my God, do I pray?\nI pray every single day for revolution\n\nAnd so I cry sometimes when I\'m lying in bed\nJust to get it all out, what\'s in my head\nAnd I, I am feeling a little peculiar\nAnd so, I wake in the morning and I step outside\nAnd I take a deep breath and I get real high\nAnd I scream from the top of my lungs, "What\'s going on?"\n\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, what\'s going on?"\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\n\nAnd I say, hey-ey-ey (wake in the morning and step outside)\nHey-ey-ey (take a deep breath and I get real high)\nI said, "Hey, a-what\'s going on?" (And I scream)\n\nAnd I say, hey-ey-ey (wake in the morning and step outside)\nHey-ey, yeah-yeah-yeah (take a deep breath and I get real high)\nI said, "Hey, a-what\'s going on?" (And I scream)\n\nOoh, ooh, ooh\nOoh\n\n25 years and my life is still\nTrying to get up that great big hill of hope\nFor a destination, mm', 'subtitles': '[00:30.04] 25 years and my life is still\n[00:33.02] Trying to get up that great big hill of hope\n[00:39.12] For a destination\n[00:43.88] I realized quickly when I knew I should\n[00:47.17] That the world was made up of this brotherhood of man\n[00:53.34] For whatever that means\n[00:57.66] And so I cry sometimes when I\'m lying in bed\n[01:01.25] Just to get it all out, what\'s in my head\n[01:05.05] And I, I am feeling a little peculiar\n[01:12.10] And so, I wake in the morning and I step outside\n[01:15.66] And I take a deep breath and I get real high\n[01:19.11] And I scream from the top of my lungs, "What\'s going on?"\n[01:25.77] And I say, hey-ey-ey, hey-ey-ey\n[01:33.23] I said, "Hey, a-what\'s going on?"\n[01:39.78] And I say, hey-ey-ey, hey-ey-ey\n[01:47.31] I said, "Hey, a-what\'s going on?"\n[01:53.14] \n[01:55.64] Ooh, ooh, ooh\n[02:02.62] Ooh\n[02:06.66] \n[02:09.61] Ooh, ooh, ooh\n[02:17.06] Ooh\n[02:20.85] \n[02:23.36] And I try, oh, my God, do I try?\n[02:29.16] I try all the time, in this institution\n[02:37.61] And I pray, oh, my God, do I pray?\n[02:43.48] I pray every single day for revolution\n[02:52.02] And so I cry sometimes when I\'m lying in bed\n[02:55.49] Just to get it all out, what\'s in my head\n[02:59.03] And I, I am feeling a little peculiar\n[03:06.12] And so, I wake in the morning and I step outside\n[03:09.87] And I take a deep breath and I get real high\n[03:13.49] And I scream from the top of my lungs, "What\'s going on?"\n[03:19.20] And I say, hey-ey-ey, hey-ey-ey\n[03:27.31] I said, "Hey, what\'s going on?"\n[03:33.72] And I say, hey-ey-ey, hey-ey-ey\n[03:41.32] I said, "Hey, a-what\'s going on?"\n[03:48.20] And I say, hey-ey-ey (wake in the morning and step outside)\n[03:52.39] Hey-ey-ey (take a deep breath and I get real high)\n[03:55.56] I said, "Hey, a-what\'s going on?" (And I scream)\n[04:01.86] And I say, hey-ey-ey (wake in the morning and step outside)\n[04:06.54] Hey-ey, yeah-yeah-yeah (take a deep breath and I get real high)\n[04:09.91] I said, "Hey, a-what\'s going on?" (And I scream)\n[04:17.37] Ooh, ooh, ooh\n[04:24.81] Ooh\n[04:29.23] \n[04:32.82] 25 years and my life is still\n[04:36.44] Trying to get up that great big hill of hope\n[04:43.34] For a destination, mm\n[04:46.82] ', 'isRightToLeft': False}} -2026-01-31T01:14:04.971495Z [DEBUG] logger.debug: DEBUG: [API.Tidal] merged_md keys: ['id', 'title', 'duration', 'replayGain', 'peak', 'allowStreaming', 'streamReady', 'payToStream', 'adSupportedStreamReady', 'djReady', 'stemReady', 'streamStartDate', 'premiumStreamingOnly', 'trackNumber', 'volumeNumber', 'version', 'popularity', 'copyright', 'bpm', 'key', 'keyScale', 'url', 'isrc', 'editable', 'explicit', 'audioQuality', 'audioModes', 'mediaMetadata', 'upload', 'accessType', 'spotlighted', 'artist', 'artists', 'album', 'mixes', 'trackId', 'assetPresentation', 'audioMode', 'manifestMimeType', 'manifestHash', 'manifest', 'albumReplayGain', 'albumPeakAmplitude', 'trackReplayGain', 'trackPeakAmplitude', 'bitDepth', 'sampleRate'] -2026-01-31T01:14:21.893079Z [DEBUG] logger.debug: DEBUG: [API.Tidal] generated tags: {'quality:lossless', 'album:Bigger, Better, Faster, More !', 'tidal', 'track:3', 'artist:4 Non Blondes', 'lossless', "title:What's Up?"} -2026-01-31T01:14:38.838805Z [DEBUG] logger.debug: DEBUG: [API.Tidal] returning full_track_metadata keys: ['metadata', 'parsed', 'tags', 'lyrics'] -2026-01-31T01:14:55.778997Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:15:12.718768Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:15:29.648299Z [DEBUG] logger.debug: DEBUG: [API.Tidal] info_resp (len=1253): {'version': '2.2', 'data': {'id': 634519, 'title': "What's Up?", 'duration': 296, 'replayGain': -7.13, 'peak': 0.977234, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '1992-10-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 3, 'volumeNumber': 1, 'version': None, 'popularity': 84, 'copyright': '℗ 1992 UMG Recordings, Inc.', 'bpm': 135, 'key': None, 'keyScale': None, 'url': 'http://www.tidal.com/track/634519', 'isrc': 'USIR19200553', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': 'PUBLIC', 'spotlighted': False, 'artist': {'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}, 'artists': [{'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}], 'album': {'id': 634516, 'title': 'Bigger, Better, Faster, More !', 'cover': '8f0a6253-153d-4049-8b1e-39d1959f50cb', 'vibrantColor': '#eae3cf', 'videoCover': None}, 'mixes': {'TRACK_MIX': '0014e63a4d993121badae9251e5a13'}}} -2026-01-31T01:15:46.567462Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:16:03.487795Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:16:20.428954Z [DEBUG] logger.debug: DEBUG: [API.Tidal] track_resp (len=752): {'version': '2.2', 'data': {'trackId': 634519, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'audioQuality': 'LOSSLESS', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': '77M6qmPIFtMMElsdireVDT8Ohxum/b5XUU0EBXEmXJY=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUtBZ0RFaVE1TVRkalptSTRaV1U1TURsbU4yVTVaalJtTUdKaU0yRmlZVEEyTW1abU5TNXRjRFEvMC5mbGFjP3Rva2VuPTE3Njk4MjMxOTN+TlRGaU9HWTNOVEF3T0RBM05XVXdPRFprTkRCbFpHSXlOamt5WVdSaE5XSXlZV1EzTkdGaFpBPT0iXX0=', 'albumReplayGain': -7.13, 'albumPeakAmplitude': 0.977234, 'trackReplayGain': -5.4, 'trackPeakAmplitude': 0.977234, 'bitDepth': 16, 'sampleRate': 44100}} -2026-01-31T01:16:37.359903Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:16:54.291062Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:17:11.216853Z [DEBUG] logger.debug: DEBUG: [API.Tidal] lyrics_resp (len=4211): {'version': '2.2', 'lyrics': {'trackId': 634519, 'lyricsProvider': 'MUSIXMATCH', 'providerCommontrackId': '10421751', 'providerLyricsId': '42557227', 'lyrics': '25 years and my life is still\nTrying to get up that great big hill of hope\nFor a destination\nI realized quickly when I knew I should\nThat the world was made up of this brotherhood of man\nFor whatever that means\n\nAnd so I cry sometimes when I\'m lying in bed\nJust to get it all out, what\'s in my head\nAnd I, I am feeling a little peculiar\nAnd so, I wake in the morning and I step outside\nAnd I take a deep breath and I get real high\nAnd I scream from the top of my lungs, "What\'s going on?"\n\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\n\nOoh, ooh, ooh\nOoh\nOoh, ooh, ooh\nOoh\n\nAnd I try, oh, my God, do I try?\nI try all the time, in this institution\nAnd I pray, oh, my God, do I pray?\nI pray every single day for revolution\n\nAnd so I cry sometimes when I\'m lying in bed\nJust to get it all out, what\'s in my head\nAnd I, I am feeling a little peculiar\nAnd so, I wake in the morning and I step outside\nAnd I take a deep breath and I get real high\nAnd I scream from the top of my lungs, "What\'s going on?"\n\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, what\'s going on?"\nAnd I say, hey-ey-ey, hey-ey-ey\nI said, "Hey, a-what\'s going on?"\n\nAnd I say, hey-ey-ey (wake in the morning and step outside)\nHey-ey-ey (take a deep breath and I get real high)\nI said, "Hey, a-what\'s going on?" (And I scream)\n\nAnd I say, hey-ey-ey (wake in the morning and step outside)\nHey-ey, yeah-yeah-yeah (take a deep breath and I get real high)\nI said, "Hey, a-what\'s going on?" (And I scream)\n\nOoh, ooh, ooh\nOoh\n\n25 years and my life is still\nTrying to get up that great big hill of hope\nFor a destination, mm', 'subtitles': '[00:30.04] 25 years and my life is still\n[00:33.02] Trying to get up that great big hill of hope\n[00:39.12] For a destination\n[00:43.88] I realized quickly when I knew I should\n[00:47.17] That the world was made up of this brotherhood of man\n[00:53.34] For whatever that means\n[00:57.66] And so I cry sometimes when I\'m lying in bed\n[01:01.25] Just to get it all out, what\'s in my head\n[01:05.05] And I, I am feeling a little peculiar\n[01:12.10] And so, I wake in the morning and I step outside\n[01:15.66] And I take a deep breath and I get real high\n[01:19.11] And I scream from the top of my lungs, "What\'s going on?"\n[01:25.77] And I say, hey-ey-ey, hey-ey-ey\n[01:33.23] I said, "Hey, a-what\'s going on?"\n[01:39.78] And I say, hey-ey-ey, hey-ey-ey\n[01:47.31] I said, "Hey, a-what\'s going on?"\n[01:53.14] \n[01:55.64] Ooh, ooh, ooh\n[02:02.62] Ooh\n[02:06.66] \n[02:09.61] Ooh, ooh, ooh\n[02:17.06] Ooh\n[02:20.85] \n[02:23.36] And I try, oh, my God, do I try?\n[02:29.16] I try all the time, in this institution\n[02:37.61] And I pray, oh, my God, do I pray?\n[02:43.48] I pray every single day for revolution\n[02:52.02] And so I cry sometimes when I\'m lying in bed\n[02:55.49] Just to get it all out, what\'s in my head\n[02:59.03] And I, I am feeling a little peculiar\n[03:06.12] And so, I wake in the morning and I step outside\n[03:09.87] And I take a deep breath and I get real high\n[03:13.49] And I scream from the top of my lungs, "What\'s going on?"\n[03:19.20] And I say, hey-ey-ey, hey-ey-ey\n[03:27.31] I said, "Hey, what\'s going on?"\n[03:33.72] And I say, hey-ey-ey, hey-ey-ey\n[03:41.32] I said, "Hey, a-what\'s going on?"\n[03:48.20] And I say, hey-ey-ey (wake in the morning and step outside)\n[03:52.39] Hey-ey-ey (take a deep breath and I get real high)\n[03:55.56] I said, "Hey, a-what\'s going on?" (And I scream)\n[04:01.86] And I say, hey-ey-ey (wake in the morning and step outside)\n[04:06.54] Hey-ey, yeah-yeah-yeah (take a deep breath and I get real high)\n[04:09.91] I said, "Hey, a-what\'s going on?" (And I scream)\n[04:17.37] Ooh, ooh, ooh\n[04:24.81] Ooh\n[04:29.23] \n[04:32.82] 25 years and my life is still\n[04:36.44] Trying to get up that great big hill of hope\n[04:43.34] For a destination, mm\n[04:46.82] ', 'isRightToLeft': False}} -2026-01-31T01:17:28.168543Z [DEBUG] logger.debug: DEBUG: [API.Tidal] merged_md keys: ['id', 'title', 'duration', 'replayGain', 'peak', 'allowStreaming', 'streamReady', 'payToStream', 'adSupportedStreamReady', 'djReady', 'stemReady', 'streamStartDate', 'premiumStreamingOnly', 'trackNumber', 'volumeNumber', 'version', 'popularity', 'copyright', 'bpm', 'key', 'keyScale', 'url', 'isrc', 'editable', 'explicit', 'audioQuality', 'audioModes', 'mediaMetadata', 'upload', 'accessType', 'spotlighted', 'artist', 'artists', 'album', 'mixes', 'trackId', 'assetPresentation', 'audioMode', 'manifestMimeType', 'manifestHash', 'manifest', 'albumReplayGain', 'albumPeakAmplitude', 'trackReplayGain', 'trackPeakAmplitude', 'bitDepth', 'sampleRate'] -2026-01-31T01:17:45.114955Z [DEBUG] logger.debug: DEBUG: [API.Tidal] generated tags: {'quality:lossless', 'album:Bigger, Better, Faster, More !', 'tidal', 'track:3', 'artist:4 Non Blondes', 'lossless', "title:What's Up?"} -2026-01-31T01:18:02.060721Z [DEBUG] logger.debug: DEBUG: [API.Tidal] returning full_track_metadata keys: ['metadata', 'parsed', 'tags', 'lyrics'] -2026-01-31T01:18:19.015327Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T01:18:35.929927Z [DEBUG] logger.debug: DEBUG: ✓ Successfully processed 1 file(s) -2026-01-31T01:18:52.867217Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:19:09.795941Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=dict -2026-01-31T01:19:26.733791Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=rpi, provider=None, delete=False -2026-01-31T01:19:43.695314Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-01-31T01:20:00.637984Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] get_file(hash=5c7296f1a554..., url=None) -2026-01-31T01:20:17.576369Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:20:34.494044Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:20:51.442975Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] get_file: falling back to url=http://127.0.0.1:45869/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&Hydrus-Client-API-Access-Key=95178b08e6ba3c57991e7b4e162c6efff1ce90c500005c6ebf8524122ed2486e -2026-01-31T01:21:08.333358Z [DEBUG] logger.debug: DEBUG: [add-file] RESOLVED source: path=C:\Users\Admin\AppData\Local\Temp\Medios-Macina\tidal-634519-77M6qmPIFtMM-What's Up_.flac, hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f... -2026-01-31T01:21:25.245022Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:21:42.198835Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:21:59.125553Z [DEBUG] logger.debug: DEBUG: [add-file] Deferring tag application until after Hydrus upload -2026-01-31T01:22:16.047947Z [DEBUG] logger.debug: DEBUG: [add-file] Storing into backend 'rpi' path='C:\Users\Admin\AppData\Local\Temp\Medios-Macina\tidal-634519-77M6qmPIFtMM-What's Up_.flac' title='What's Up?' hash='5c7296f1a554' -2026-01-31T01:22:32.997398Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] file hash: 5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:22:49.932465Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:23:06.868161Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:23:23.826237Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Uploading: tidal-634519-77M6qmPIFtMM-What's Up_.flac -2026-01-31T01:23:40.775836Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:23:57.709836Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:24:14.623284Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] hash: 5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:24:31.571890Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Adding 1 tag(s): ["title:what's up?"] -2026-01-31T01:24:48.523230Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:25:05.453758Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:25:22.377352Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Tags added via 'my tags' -2026-01-31T01:25:39.281833Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Associating 2 URL(s) with file -2026-01-31T01:25:56.192463Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:26:13.091549Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:26:30.040290Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Associated URL: tidal://track/634519 -2026-01-31T01:26:46.976122Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:27:03.893454Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:27:20.812981Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Associated URL: http://www.tidal.com/track/634519 -2026-01-31T01:27:37.750956Z [DEBUG] logger.debug: DEBUG: [add-file] backend.add_file returned identifier 5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f (len=64) -2026-01-31T01:27:54.681097Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] get_file(hash=5c7296f1a554..., url=None) -2026-01-31T01:28:11.610582Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:28:28.510206Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:28:45.397945Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] get_file: server path not locally accessible, falling back to HTTP: /mnt/ext-drive/hydrus/hydrusnetwork/db/client_files/f5c/5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f.flac -2026-01-31T01:29:02.286783Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] get_file: falling back to url=http://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&Hydrus-Client-API-Access-Key=d701cad8f830210a81af2df30c9a7f82bb33d21130b4efe33a3feeca78be9b81 -2026-01-31T01:29:19.183780Z [DEBUG] logger.debug: DEBUG: [add-file] Applying 7 tag(s) post-upload to Hydrus -2026-01-31T01:29:36.056518Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:29:52.950638Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:30:09.833567Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:30:26.702623Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:30:43.593406Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:31:00.458350Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:31:17.340420Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:31:34.231228Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:31:51.104401Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:32:07.975971Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:32:24.868281Z [DEBUG] logger.debug: DEBUG: [add-file] Writing lyric note (len=2211) to rpi:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:32:41.730196Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:32:58.626982Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:33:15.514487Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:33:32.374014Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:33:49.243511Z [DEBUG] logger.debug: DEBUG: pipe._run: received result type= repr=PipeObject(hash='unknown', store='PATH', provider='tidal', tag=['quality:lossless', 'tidal', "title:Phone Sex (That's What's Up)", 'lossless', 'track:8', 'album:Private Room', 'artist:Avant'], title=" -2026-01-31T01:34:06.123977Z [DEBUG] logger.debug: DEBUG: pipe._run: attrs path=tidal://track/3595555 url=http://www.tidal.com/track/3595555 store=PATH hash=unknown -2026-01-31T01:34:22.975634Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T01:34:39.831552Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T01:34:56.662747Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:35:13.500253Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='unknown', store='PATH', provider='tidal', tag=['quality:lossless', 'tidal', "title:Phone Sex (That's What's Up)", 'lossless', 'track:8', 'album:Private Room', 'artist:Avant'], title=" -2026-01-31T01:35:30.380009Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=PATH, path=tidal://track/3595555, hash=unknown -2026-01-31T01:35:47.264540Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,Phone Sex (That's What's Up) -https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ== mode=append wait=True -2026-01-31T01:36:04.153456Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: None -2026-01-31T01:36:21.006994Z [DEBUG] logger.debug: DEBUG: MPV not running/died while queuing, starting MPV with remaining items: [PipeObject(hash='unknown', store='PATH', provider='tidal', tag=['quality:lossless', 'tidal', "title:Phone Sex (That's What's Up)", 'lossless', 'track:8', 'album:Private Room', 'artist:Avant'], title="Phone Sex (That's What's Up)", url='http://www.tidal.com/track/3595555', source_url=None, duration=None, metadata={'id': 3595555, 'title': "Phone Sex (That's What's Up)", 'duration': 303, 'replayGain': -9.28, 'peak': 0.999969, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '2003-12-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 8, 'volumeNumber': 1, 'version': None, 'popularity': 70, 'copyright': '℗ 2003 Geffen Records', 'bpm': 128, 'key': 'Eb', 'keyScale': 'MAJOR', 'url': 'http://www.tidal.com/track/3595555', 'isrc': 'USMC10346207', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': None, 'spotlighted': False, 'artist': {'id': 32909, 'name': 'Avant', 'handle': None, 'type': 'MAIN', 'picture': '6714a574-ae92-4c39-b50f-2659a2934721'}, 'artists': [{'id': 32909, 'name': 'Avant', 'handle': None, 'type': 'MAIN', 'picture': '6714a574-ae92-4c39-b50f-2659a2934721'}], 'album': {'id': 3595547, 'title': 'Private Room', 'cover': '2b424afd-a60e-4ebd-bcae-dd6049d3254e', 'vibrantColor': '#e1c9a2', 'videoCover': None}, 'mixes': {'TRACK_MIX': '001f5df09c7fdc9411c23388f8e1c9'}, 'trackId': 3595555, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': 'hNY+nQIWOD0Jzvcnl9TzkzmqRJJeZVJNUjF/SbddrOI=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUt3Z0RFaWMzTURWallUTTVZemt6Tmpoa09URXlaRFpqTm1VMk1XRTBZbUl6TkdRMU5sODJNUzV0Y0RRLzAuZmxhYz90b2tlbj0xNzY5ODIzMjQ0fk9HWTFOV1l5WTJaalkySTNZbU5qTnpZd1ltUTVPVFE1T0dZMk1EVmlNVEEwWm1VeVpqaGhPUT09Il19', 'albumReplayGain': -9.28, 'albumPeakAmplitude': 0.999969, 'trackReplayGain': -6.95, 'trackPeakAmplitude': 0.999969, 'bitDepth': 16, 'sampleRate': 44100, '_tidal_track_details_fetched': True, '_tidal_manifest_url': 'https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ=='}, warnings=[], path='tidal://track/3595555', relationships={}, is_temp=False, action=None, parent_hash=None, extra={'table': 'tidal.track', 'detail': 'tidal.track', 'annotations': ['tidal', 'track'], 'media_kind': 'audio', 'size_bytes': None, 'columns': [('Title', "Phone Sex (That's What's Up)"), ('Disc #', '1'), ('Track #', '8'), ('Album', 'Private Room'), ('Artist', 'Avant'), ('Duration', '5:03'), ('Quality', 'LOSSLESS')], 'full_metadata': {'id': 3595555, 'title': "Phone Sex (That's What's Up)", 'duration': 303, 'replayGain': -9.28, 'peak': 0.999969, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '2003-12-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 8, 'volumeNumber': 1, 'version': None, 'popularity': 70, 'copyright': '℗ 2003 Geffen Records', 'bpm': 128, 'key': 'Eb', 'keyScale': 'MAJOR', 'url': 'http://www.tidal.com/track/3595555', 'isrc': 'USMC10346207', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': None, 'spotlighted': False, 'artist': {'id': 32909, 'name': 'Avant', 'handle': None, 'type': 'MAIN', 'picture': '6714a574-ae92-4c39-b50f-2659a2934721'}, 'artists': [{'id': 32909, 'name': 'Avant', 'handle': None, 'type': 'MAIN', 'picture': '6714a574-ae92-4c39-b50f-2659a2934721'}], 'album': {'id': 3595547, 'title': 'Private Room', 'cover': '2b424afd-a60e-4ebd-bcae-dd6049d3254e', 'vibrantColor': '#e1c9a2', 'videoCover': None}, 'mixes': {'TRACK_MIX': '001f5df09c7fdc9411c23388f8e1c9'}, 'trackId': 3595555, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': 'hNY+nQIWOD0Jzvcnl9TzkzmqRJJeZVJNUjF/SbddrOI=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUt3Z0RFaWMzTURWallUTTVZemt6Tmpoa09URXlaRFpqTm1VMk1XRTBZbUl6TkdRMU5sODJNUzV0Y0RRLzAuZmxhYz90b2tlbj0xNzY5ODIzMjQ0fk9HWTFOV1l5WTJaalkySTNZbU5qTnpZd1ltUTVPVFE1T0dZMk1EVmlNVEEwWm1VeVpqaGhPUT09Il19', 'albumReplayGain': -9.28, 'albumPeakAmplitude': 0.999969, 'trackReplayGain': -6.95, 'trackPeakAmplitude': 0.999969, 'bitDepth': 16, 'sampleRate': 44100, '_tidal_track_details_fetched': True, '_tidal_manifest_url': 'https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ=='}, '_selection_args': ['-url', 'tidal://track/3595555'], 'source': 'tidal'})] -2026-01-31T01:36:37.900054Z [DEBUG] logger.debug: DEBUG: Starting MPV with cookies file: C:/Forgejo/Medios-Macina/cookies.txt -2026-01-31T01:36:54.779732Z [DEBUG] logger.debug: DEBUG: Starting MPV -2026-01-31T01:37:11.664840Z [DEBUG] logger.debug: DEBUG: Started MPV process -2026-01-31T01:37:28.525721Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-store", "PATH"], "request_id": 901} -2026-01-31T01:37:45.367374Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":901,"error":"success"} -2026-01-31T01:38:02.224766Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-hash", "unknown"], "request_id": 902} -2026-01-31T01:38:19.072790Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":902,"error":"success"} -2026-01-31T01:38:35.964322Z [DEBUG] logger.debug: DEBUG: Lyric loader started (log=C:\Forgejo\Medios-Macina\Log\medeia-mpv-lyric.log) -2026-01-31T01:38:52.819719Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T01:39:09.704034Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T01:39:26.569256Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:39:43.443339Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T01:40:00.273467Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[],"request_id":100,"error":"success"} -2026-01-31T01:40:17.111094Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='unknown', store='PATH', provider='tidal', tag=['quality:lossless', 'tidal', "title:Phone Sex (That's What's Up)", 'lossless', 'track:8', 'album:Private Room', 'artist:Avant'], title=" -2026-01-31T01:40:33.967361Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=PATH, path=tidal://track/3595555, hash=unknown -2026-01-31T01:40:50.832025Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,Phone Sex (That's What's Up) -https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ== mode=append wait=True -2026-01-31T01:41:07.718091Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["loadlist", "memory://#EXTM3U\n#EXTINF:-1,Phone Sex (That's What's Up)\nhttps://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ==", "append"], "request_id": 200} -2026-01-31T01:41:24.594066Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":{"playlist_entry_id":1,"num_entries":1},"request_id":200,"error":"success"} -2026-01-31T01:41:41.470528Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: {'data': {'playlist_entry_id': 1, 'num_entries': 1}, 'request_id': 200, 'error': 'success'} -2026-01-31T01:41:58.347857Z [DEBUG] logger.debug: DEBUG: Queued: Phone Sex (That's What's Up) -2026-01-31T01:42:15.212658Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["playlist-play-index", 0], "request_id": 102} -2026-01-31T01:42:32.097885Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":null,"request_id":102,"error":"success"} -2026-01-31T01:42:48.968993Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "pause", false], "request_id": 103} -2026-01-31T01:43:05.828716Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":103,"error":"success"} -2026-01-31T01:43:22.709205Z [DEBUG] logger.debug: DEBUG: Auto-playing first item -2026-01-31T01:43:39.568436Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T01:43:56.429518Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[{"filename":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ==","current":true,"playing":true,"title":"Phone Sex (That's What's Up)","id":1,"playlist-path":"memory://#EXTM3U\n#EXTINF:-1,Phone Sex (That's What's Up)\nhttps://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic3MDVjYTM5YzkzNjhkOTEyZDZjNmU2MWE0YmIzNGQ1Nl82MS5tcDQ/0.flac?token=1769823244~OGY1NWYyY2ZjY2I3YmNjNzYwYmQ5OTQ5OGY2MDViMTA0ZmUyZjhhOQ=="}],"request_id":100,"error":"success"} -2026-01-31T01:44:13.300509Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:44:30.171538Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:44:47.035695Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:45:03.918318Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T01:45:20.760527Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T01:45:37.639974Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: what's -2026-01-31T01:45:54.519004Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:46:11.392276Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:46:28.241745Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:46:45.126917Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:47:02.006189Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:47:18.880331Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:47:35.752934Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 1 result(s) -2026-01-31T01:47:52.597678Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 1 result(s) -2026-01-31T01:48:09.395539Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T01:48:26.234541Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: what's -2026-01-31T01:48:43.087563Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:48:59.957473Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:49:16.823831Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:49:33.681602Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:49:50.541777Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:50:07.404447Z [DEBUG] logger.debug: DEBUG: -2026-01-31T01:50:24.206586Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 16 result(s) -2026-01-31T01:50:41.021554Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 16 result(s) -2026-01-31T01:50:57.885918Z [DEBUG] logger.debug: DEBUG: pipe._run: received result type= repr=PipeObject(hash='5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', store='rpi', provider=None, tag=['album:bigger, better, faster, more !', 'artist:4 non blondes', 'lossless', 'qualit -2026-01-31T01:51:14.751415Z [DEBUG] logger.debug: DEBUG: pipe._run: attrs path=None url=http://www.tidal.com/track/634519 store=rpi hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:51:31.604222Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T01:51:48.431910Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T01:52:05.262875Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:52:22.169598Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', store='rpi', provider=None, tag=['album:bigger, better, faster, more !', 'artist:4 non blondes', 'lossless', 'qualit -2026-01-31T01:52:39.029212Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=rpi, path=http://www.tidal.com/track/634519, hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:52:55.895233Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,what's up? -http://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&store=rpi mode=append wait=True -2026-01-31T01:53:12.763875Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: None -2026-01-31T01:53:29.649098Z [DEBUG] logger.debug: DEBUG: MPV not running/died while queuing, starting MPV with remaining items: [PipeObject(hash='5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', store='rpi', provider=None, tag=['album:bigger, better, faster, more !', 'artist:4 non blondes', 'lossless', 'quality:lossless', 'tidal', "title:what's up?", 'track:3'], title="what's up?", url='http://www.tidal.com/track/634519', source_url=None, duration=None, metadata={}, warnings=[], path=None, relationships={}, is_temp=False, action=None, parent_hash=None, extra={'name': "what's up?", 'size': 33211393, 'size_bytes': 33211393, 'file_id': 522, 'mime': 'audio/flac', 'ext': 'flac', '_selection_args': ['-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'], '_selection_action': ['get-metadata', '-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'], 'url': ['http://www.tidal.com/track/634519', 'tidal://track/634519']})] -2026-01-31T01:53:46.532133Z [DEBUG] logger.debug: DEBUG: Starting MPV with cookies file: C:/Forgejo/Medios-Macina/cookies.txt -2026-01-31T01:54:03.386036Z [DEBUG] logger.debug: DEBUG: Starting MPV -2026-01-31T01:54:20.248682Z [DEBUG] logger.debug: DEBUG: Started MPV process -2026-01-31T01:54:37.094125Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-store", "rpi"], "request_id": 901} -2026-01-31T01:54:53.906415Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":901,"error":"success"} -2026-01-31T01:55:10.716561Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "user-data/medeia-item-hash", "5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f"], "request_id": 902} -2026-01-31T01:55:27.409991Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":902,"error":"success"} -2026-01-31T01:55:44.224269Z [DEBUG] logger.debug: DEBUG: Lyric loader started (log=C:\Forgejo\Medios-Macina\Log\medeia-mpv-lyric.log) -2026-01-31T01:56:01.074114Z [DEBUG] logger.debug: DEBUG: _queue_items: count=1 types=['PipeObject'] -2026-01-31T01:56:17.887688Z [DEBUG] logger.debug: DEBUG: Cookies file verified: C:/Forgejo/Medios-Macina/cookies.txt (4907 bytes) -2026-01-31T01:56:34.726553Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T01:56:51.554229Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T01:57:08.426861Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[],"request_id":100,"error":"success"} -2026-01-31T01:57:25.236435Z [DEBUG] logger.debug: DEBUG: _queue_items: processing idx=0 type= repr=PipeObject(hash='5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', store='rpi', provider=None, tag=['album:bigger, better, faster, more !', 'artist:4 non blondes', 'lossless', 'qualit -2026-01-31T01:57:42.067566Z [DEBUG] logger.debug: DEBUG: _get_playable_path: store=rpi, path=http://www.tidal.com/track/634519, hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f -2026-01-31T01:57:58.868098Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "http-header-fields", "Hydrus-Client-API-Access-Key: d701cad8f830210a81af2df30c9a7f82bb33d21130b4efe33a3feeca78be9b81"], "request_id": 199} -2026-01-31T01:58:15.725596Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":199,"error":"success"} -2026-01-31T01:58:32.587719Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "ytdl-raw-options", "cookies=C:/Forgejo/Medios-Macina/cookies.txt,add-header=Hydrus-Client-API-Access-Key: d701cad8f830210a81af2df30c9a7f82bb33d21130b4efe33a3feeca78be9b81"], "request_id": 197} -2026-01-31T01:58:49.441360Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":197,"error":"success"} -2026-01-31T01:59:06.227591Z [DEBUG] logger.debug: DEBUG: Sending MPV loadlist: memory://#EXTM3U -#EXTINF:-1,what's up? -http://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&store=rpi mode=append wait=True -2026-01-31T01:59:23.053739Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["loadlist", "memory://#EXTM3U\n#EXTINF:-1,what's up?\nhttp://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&store=rpi", "append"], "request_id": 200} -2026-01-31T01:59:39.918853Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":{"playlist_entry_id":1,"num_entries":1},"request_id":200,"error":"success"} -2026-01-31T01:59:56.767148Z [DEBUG] logger.debug: DEBUG: MPV loadlist response: {'data': {'playlist_entry_id': 1, 'num_entries': 1}, 'request_id': 200, 'error': 'success'} -2026-01-31T02:00:13.609347Z [DEBUG] logger.debug: DEBUG: Queued: what's up? -2026-01-31T02:00:30.384526Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["playlist-play-index", 0], "request_id": 102} -2026-01-31T02:00:47.153138Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":null,"request_id":102,"error":"success"} -2026-01-31T02:01:03.970720Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["set_property", "pause", false], "request_id": 103} -2026-01-31T02:01:20.802076Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"request_id":103,"error":"success"} -2026-01-31T02:01:37.636942Z [DEBUG] logger.debug: DEBUG: Auto-playing first item -2026-01-31T02:01:54.496398Z [DEBUG] logger.debug: DEBUG: [IPC] Sending: {"command": ["get_property", "playlist"], "request_id": 100} -2026-01-31T02:02:11.351310Z [DEBUG] logger.debug: DEBUG: [IPC] Received: {"data":[{"filename":"http://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&store=rpi","current":true,"playing":true,"title":"what's up?","id":1,"playlist-path":"memory://#EXTM3U\n#EXTINF:-1,what's up?\nhttp://10.162.158.28:45899/get_files/file?hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f&store=rpi"}],"request_id":100,"error":"success"} -2026-01-31T02:02:28.217492Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:02:45.052248Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:03:01.873287Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:03:18.702234Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:03:35.522218Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:03:52.339278Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:04:09.192311Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:04:26.028283Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T02:04:42.888485Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T02:04:59.727857Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: what's -2026-01-31T02:05:16.569577Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:05:33.430790Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:05:50.271905Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:06:07.138849Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:06:23.994356Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:06:40.861186Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:06:57.724748Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 1 result(s) -2026-01-31T02:07:14.595101Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 1 result(s) -2026-01-31T02:07:31.552289Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T02:07:48.401146Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: what's -2026-01-31T02:08:05.170760Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:08:22.048224Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:08:38.920171Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:08:55.715845Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:09:12.579087Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:09:29.450433Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:09:46.286545Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 16 result(s) -2026-01-31T02:10:03.151049Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 16 result(s) -2026-01-31T02:10:19.980696Z [DEBUG] logger.debug: DEBUG: @N initial selection idx=0 last_items=17 -2026-01-31T02:10:36.790729Z [DEBUG] logger.debug: DEBUG: @N payload: hash=8d05868e1e64147391867c1b8b3f25a6c2e8f13409319d06261533d2b587de14 store=local _selection_args=['-query', 'hash:8d05868e1e64147391867c1b8b3f25a6c2e8f13409319d06261533d2b587de14', '-store', 'local'] _selection_action=['get-metadata', '-query', 'hash:8d05868e1e64147391867c1b8b3f25a6c2e8f13409319d06261533d2b587de14', '-store', 'local'] -2026-01-31T02:10:53.626630Z [DEBUG] logger.debug: DEBUG: @N restored row_action from payload: ['get-metadata', '-query', 'hash:8d05868e1e64147391867c1b8b3f25a6c2e8f13409319d06261533d2b587de14', '-store', 'local'] -2026-01-31T02:11:10.459717Z [DEBUG] logger.debug: DEBUG: @N applying row action -> ['get-metadata', '-query', 'hash:8d05868e1e64147391867c1b8b3f25a6c2e8f13409319d06261533d2b587de14', '-store', 'local'] -2026-01-31T02:11:27.339298Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:11:44.160255Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:12:01.014707Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:12:17.870395Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:12:34.780541Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:12:51.652750Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:13:08.433127Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:13:25.281193Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:13:42.143401Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-01-31T02:13:59.025318Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-01-31T02:14:15.973786Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: what's -2026-01-31T02:14:32.873881Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:14:49.796030Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:15:06.709689Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:15:23.576550Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:15:40.482780Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:15:57.362816Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:16:14.236726Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 1 result(s) -2026-01-31T02:16:31.139565Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 1 result(s) -2026-01-31T02:16:48.008064Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-01-31T02:17:04.889802Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: what's -2026-01-31T02:17:21.777664Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:17:38.649476Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:17:55.559286Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:18:12.520251Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:18:29.388914Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:18:46.216217Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:19:03.038492Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 16 result(s) -2026-01-31T02:19:19.915478Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 16 result(s) -2026-01-31T02:19:36.781552Z [DEBUG] logger.debug: DEBUG: @N initial selection idx=14 last_items=17 -2026-01-31T02:19:53.665478Z [DEBUG] logger.debug: DEBUG: @N payload: hash=5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f store=rpi _selection_args=['-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'] _selection_action=['get-metadata', '-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'] -2026-01-31T02:20:10.540748Z [DEBUG] logger.debug: DEBUG: @N restored row_action from payload: ['get-metadata', '-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'] -2026-01-31T02:20:27.407465Z [DEBUG] logger.debug: DEBUG: @N applying row action -> ['get-metadata', '-query', 'hash:5c7296f1a5544522e3d118f60080e0389ec2f01ace559ca69de58c352faf793f', '-store', 'rpi'] -2026-01-31T02:20:44.272513Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:21:01.178282Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:21:18.042304Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:21:34.921402Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:21:51.787612Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:22:08.540359Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:22:25.413403Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['https://tidal.com/track/634523/u'] -2026-01-31T02:22:42.295728Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T02:22:59.218707Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T02:23:16.083498Z [DEBUG] logger.debug: DEBUG: Processing URL: https://tidal.com/track/634523/u -2026-01-31T02:23:32.951312Z [DEBUG] logger.debug: DEBUG: Provider tidal claimed https://tidal.com/track/634523/u -2026-01-31T02:23:49.850798Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:24:06.681752Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:24:23.498050Z [DEBUG] logger.debug: DEBUG: [API.Tidal] info_resp (len=1259): {'version': '2.2', 'data': {'id': 634523, 'title': 'Old Mr. Heffer', 'duration': 137, 'replayGain': -7.13, 'peak': 0.977203, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '1992-10-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 7, 'volumeNumber': 1, 'version': None, 'popularity': 41, 'copyright': '℗ 1992 UMG Recordings, Inc.', 'bpm': 115, 'key': 'A', 'keyScale': 'MAJOR', 'url': 'http://www.tidal.com/track/634523', 'isrc': 'USIR19200557', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': 'PUBLIC', 'spotlighted': False, 'artist': {'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}, 'artists': [{'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}], 'album': {'id': 634516, 'title': 'Bigger, Better, Faster, More !', 'cover': '8f0a6253-153d-4049-8b1e-39d1959f50cb', 'vibrantColor': '#eae3cf', 'videoCover': None}, 'mixes': {'TRACK_MIX': '001f9d683f3e787a9a95e640deb48f'}}} -2026-01-31T02:24:40.328450Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:24:57.188557Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:25:14.045453Z [DEBUG] logger.debug: DEBUG: [API.Tidal] track_resp (len=753): {'version': '2.2', 'data': {'trackId': 634523, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'audioQuality': 'LOSSLESS', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': 'oNFqlrIjI8ERatdjuNRMitBOGi1RflnaYnWWsBvEn4M=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUtBZ0RFaVF5TVdVek1ERTNZVGhpWTJFNU56Y3haV0ZtWXpjeE9EYzRNV05pWTJJMU1DNXRjRFEvMC5mbGFjP3Rva2VuPTE3Njk4MjQzNDh+WmpZME5UazFObVZoWm1Fd1pEVTRZemcyWmpFNU5qazRZamxpWWpGak5USTRaakEyTVRCa09BPT0iXX0=', 'albumReplayGain': -7.13, 'albumPeakAmplitude': 0.977234, 'trackReplayGain': -7.13, 'trackPeakAmplitude': 0.977203, 'bitDepth': 16, 'sampleRate': 44100}} -2026-01-31T02:25:30.946914Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:25:47.803824Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:26:04.674492Z [DEBUG] logger.debug: DEBUG: [API.Tidal] lyrics_resp (len=2838): {'version': '2.2', 'lyrics': {'trackId': 634523, 'lyricsProvider': 'MUSIXMATCH', 'providerCommontrackId': '314992', 'providerLyricsId': '18388911', 'lyrics': "Stumbled my way on the darkest afternoon\nI got a beer in my hand and I'm draggin' a stoagle too\nthe back of my brain is tickin' like a clock\nI simmer down gently but boil on what the f\nGet back 'cause I'm feelin' good tonight\nget back 'cause I'm feelin' right\nTrouble is a word that stars with a capital T\nI refer myself to the word 'cause I'm so keen\nlittle do they know that I'm struttin' such a style\nit makes the trouble in me all worth the while\nSo get back 'cause I'm feelin' good tonight\nget back 'cause I'm feelin' right\nThere goes Billy and Susie walkin' hand by hand\nI quickly caught up slurring yo what's the plan\nthey had fear in their eyes and bellies that ran like dogs\nI barrelled down laughing screaming Susie\nyou forgot your clogs\nwell, Old Mr. Heffer\nI'm really pleased to meet you\nI didn't mean to scare your blue-eyed child\nbut Billy wouldn't talk to me\nand Susie wouldn't look at me\nit made me so doggone crazy\nI had to chase them for a mile\nall wanted was change for a buck\nWell I'm back and I'm feelin' good tonight\nyea I'm back and I'm feelin' right\nso get back 'cause I'm feelin' right\nJesus!", 'subtitles': "[00:08.97] Stumbled my way on the darkest afternoon\n[00:12.92] I got a beer in my hand and I'm draggin' a stoagle too\n[00:17.03] The back of my brain is tickin' like a clock\n[00:21.12] I simmer down gently but boil on what the f\n[00:25.21] Get back 'cause I'm feelin' good tonight\n[00:29.49] Get back 'cause I'm feelin' right\n[00:35.71] Trouble is a word that stars with a capital T\n[00:39.73] I refer myself to the word 'cause I'm so keen\n[00:44.07] Little do they know that I'm struttin' such a style\n[00:47.95] It makes the trouble in me all worth the while\n[00:52.02] So get back 'cause I'm feelin' good tonight\n[00:56.32] Get back 'cause I'm feelin' right\n[01:02.78] There goes Billy and Susie walkin' hand by hand\n[01:06.79] I quickly caught up slurring yo what's the plan\n[01:10.60] They had fear in their eyes and bellies that ran like dogs\n[01:14.84] I barrelled down laughing screaming Susie\n[01:16.46] You forgot your clogs\n[01:18.36] Well, Old Mr. Heffer\n[01:21.09] I'm really pleased to meet you\n[01:23.12] I didn't mean to scare your blue-eyed child\n[01:26.78] But Billy wouldn't talk to me\n[01:29.48] And Susie wouldn't look at me\n[01:31.66] It made me so doggone crazy\n[01:34.33] I had to chase them for a mile\n[01:38.13] All wanted was change for a buck\n[01:41.55] \n[01:56.64] Well I'm back and I'm feelin' good tonight\n[02:00.97] Yea I'm back and I'm feelin' right\n[02:03.85] So get back 'cause I'm feelin' right\n[02:14.29] Jesus!\n[02:14.78] ", 'isRightToLeft': False}} -2026-01-31T02:26:21.576299Z [DEBUG] logger.debug: DEBUG: [API.Tidal] merged_md keys: ['id', 'title', 'duration', 'replayGain', 'peak', 'allowStreaming', 'streamReady', 'payToStream', 'adSupportedStreamReady', 'djReady', 'stemReady', 'streamStartDate', 'premiumStreamingOnly', 'trackNumber', 'volumeNumber', 'version', 'popularity', 'copyright', 'bpm', 'key', 'keyScale', 'url', 'isrc', 'editable', 'explicit', 'audioQuality', 'audioModes', 'mediaMetadata', 'upload', 'accessType', 'spotlighted', 'artist', 'artists', 'album', 'mixes', 'trackId', 'assetPresentation', 'audioMode', 'manifestMimeType', 'manifestHash', 'manifest', 'albumReplayGain', 'albumPeakAmplitude', 'trackReplayGain', 'trackPeakAmplitude', 'bitDepth', 'sampleRate'] -2026-01-31T02:26:38.479092Z [DEBUG] logger.debug: DEBUG: [API.Tidal] generated tags: {'quality:lossless', 'album:Bigger, Better, Faster, More !', 'tidal', 'artist:4 Non Blondes', 'lossless', 'track:7', 'title:Old Mr. Heffer'} -2026-01-31T02:26:55.399409Z [DEBUG] logger.debug: DEBUG: [API.Tidal] returning full_track_metadata keys: ['metadata', 'parsed', 'tags', 'lyrics'] -2026-01-31T02:27:12.273966Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:27:29.149093Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:27:46.040187Z [DEBUG] logger.debug: DEBUG: [API.Tidal] info_resp (len=1259): {'version': '2.2', 'data': {'id': 634523, 'title': 'Old Mr. Heffer', 'duration': 137, 'replayGain': -7.13, 'peak': 0.977203, 'allowStreaming': True, 'streamReady': True, 'payToStream': False, 'adSupportedStreamReady': True, 'djReady': True, 'stemReady': False, 'streamStartDate': '1992-10-09T00:00:00.000+0000', 'premiumStreamingOnly': False, 'trackNumber': 7, 'volumeNumber': 1, 'version': None, 'popularity': 41, 'copyright': '℗ 1992 UMG Recordings, Inc.', 'bpm': 115, 'key': 'A', 'keyScale': 'MAJOR', 'url': 'http://www.tidal.com/track/634523', 'isrc': 'USIR19200557', 'editable': False, 'explicit': False, 'audioQuality': 'LOSSLESS', 'audioModes': ['STEREO'], 'mediaMetadata': {'tags': ['LOSSLESS']}, 'upload': False, 'accessType': 'PUBLIC', 'spotlighted': False, 'artist': {'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}, 'artists': [{'id': 31920, 'name': '4 Non Blondes', 'handle': None, 'type': 'MAIN', 'picture': '14ccde00-c1b7-4641-baf1-44c75e6b442d'}], 'album': {'id': 634516, 'title': 'Bigger, Better, Faster, More !', 'cover': '8f0a6253-153d-4049-8b1e-39d1959f50cb', 'vibrantColor': '#eae3cf', 'videoCover': None}, 'mixes': {'TRACK_MIX': '001f9d683f3e787a9a95e640deb48f'}}} -2026-01-31T02:28:02.930719Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:28:19.866759Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:28:36.711793Z [DEBUG] logger.debug: DEBUG: [API.Tidal] track_resp (len=753): {'version': '2.2', 'data': {'trackId': 634523, 'assetPresentation': 'FULL', 'audioMode': 'STEREO', 'audioQuality': 'LOSSLESS', 'manifestMimeType': 'application/vnd.tidal.bts', 'manifestHash': 'oNFqlrIjI8ERatdjuNRMitBOGi1RflnaYnWWsBvEn4M=', 'manifest': 'eyJtaW1lVHlwZSI6ImF1ZGlvL2ZsYWMiLCJjb2RlY3MiOiJmbGFjIiwiZW5jcnlwdGlvblR5cGUiOiJOT05FIiwidXJscyI6WyJodHRwczovL2xnZi5hdWRpby50aWRhbC5jb20vbWVkaWF0cmFja3MvQ0FFYUtBZ0RFaVF5TVdVek1ERTNZVGhpWTJFNU56Y3haV0ZtWXpjeE9EYzRNV05pWTJJMU1DNXRjRFEvMC5mbGFjP3Rva2VuPTE3Njk4MjQzNDh+WmpZME5UazFObVZoWm1Fd1pEVTRZemcyWmpFNU5qazRZamxpWWpGak5USTRaakEyTVRCa09BPT0iXX0=', 'albumReplayGain': -7.13, 'albumPeakAmplitude': 0.977234, 'trackReplayGain': -7.13, 'trackPeakAmplitude': 0.977203, 'bitDepth': 16, 'sampleRate': 44100}} -2026-01-31T02:28:53.601831Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:29:10.520605Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:29:27.422686Z [DEBUG] logger.debug: DEBUG: [API.Tidal] lyrics_resp (len=2838): {'version': '2.2', 'lyrics': {'trackId': 634523, 'lyricsProvider': 'MUSIXMATCH', 'providerCommontrackId': '314992', 'providerLyricsId': '18388911', 'lyrics': "Stumbled my way on the darkest afternoon\nI got a beer in my hand and I'm draggin' a stoagle too\nthe back of my brain is tickin' like a clock\nI simmer down gently but boil on what the f\nGet back 'cause I'm feelin' good tonight\nget back 'cause I'm feelin' right\nTrouble is a word that stars with a capital T\nI refer myself to the word 'cause I'm so keen\nlittle do they know that I'm struttin' such a style\nit makes the trouble in me all worth the while\nSo get back 'cause I'm feelin' good tonight\nget back 'cause I'm feelin' right\nThere goes Billy and Susie walkin' hand by hand\nI quickly caught up slurring yo what's the plan\nthey had fear in their eyes and bellies that ran like dogs\nI barrelled down laughing screaming Susie\nyou forgot your clogs\nwell, Old Mr. Heffer\nI'm really pleased to meet you\nI didn't mean to scare your blue-eyed child\nbut Billy wouldn't talk to me\nand Susie wouldn't look at me\nit made me so doggone crazy\nI had to chase them for a mile\nall wanted was change for a buck\nWell I'm back and I'm feelin' good tonight\nyea I'm back and I'm feelin' right\nso get back 'cause I'm feelin' right\nJesus!", 'subtitles': "[00:08.97] Stumbled my way on the darkest afternoon\n[00:12.92] I got a beer in my hand and I'm draggin' a stoagle too\n[00:17.03] The back of my brain is tickin' like a clock\n[00:21.12] I simmer down gently but boil on what the f\n[00:25.21] Get back 'cause I'm feelin' good tonight\n[00:29.49] Get back 'cause I'm feelin' right\n[00:35.71] Trouble is a word that stars with a capital T\n[00:39.73] I refer myself to the word 'cause I'm so keen\n[00:44.07] Little do they know that I'm struttin' such a style\n[00:47.95] It makes the trouble in me all worth the while\n[00:52.02] So get back 'cause I'm feelin' good tonight\n[00:56.32] Get back 'cause I'm feelin' right\n[01:02.78] There goes Billy and Susie walkin' hand by hand\n[01:06.79] I quickly caught up slurring yo what's the plan\n[01:10.60] They had fear in their eyes and bellies that ran like dogs\n[01:14.84] I barrelled down laughing screaming Susie\n[01:16.46] You forgot your clogs\n[01:18.36] Well, Old Mr. Heffer\n[01:21.09] I'm really pleased to meet you\n[01:23.12] I didn't mean to scare your blue-eyed child\n[01:26.78] But Billy wouldn't talk to me\n[01:29.48] And Susie wouldn't look at me\n[01:31.66] It made me so doggone crazy\n[01:34.33] I had to chase them for a mile\n[01:38.13] All wanted was change for a buck\n[01:41.55] \n[01:56.64] Well I'm back and I'm feelin' good tonight\n[02:00.97] Yea I'm back and I'm feelin' right\n[02:03.85] So get back 'cause I'm feelin' right\n[02:14.29] Jesus!\n[02:14.78] ", 'isRightToLeft': False}} -2026-01-31T02:29:44.292497Z [DEBUG] logger.debug: DEBUG: [API.Tidal] merged_md keys: ['id', 'title', 'duration', 'replayGain', 'peak', 'allowStreaming', 'streamReady', 'payToStream', 'adSupportedStreamReady', 'djReady', 'stemReady', 'streamStartDate', 'premiumStreamingOnly', 'trackNumber', 'volumeNumber', 'version', 'popularity', 'copyright', 'bpm', 'key', 'keyScale', 'url', 'isrc', 'editable', 'explicit', 'audioQuality', 'audioModes', 'mediaMetadata', 'upload', 'accessType', 'spotlighted', 'artist', 'artists', 'album', 'mixes', 'trackId', 'assetPresentation', 'audioMode', 'manifestMimeType', 'manifestHash', 'manifest', 'albumReplayGain', 'albumPeakAmplitude', 'trackReplayGain', 'trackPeakAmplitude', 'bitDepth', 'sampleRate'] -2026-01-31T02:30:01.195333Z [DEBUG] logger.debug: DEBUG: [API.Tidal] generated tags: {'quality:lossless', 'album:Bigger, Better, Faster, More !', 'tidal', 'artist:4 Non Blondes', 'lossless', 'track:7', 'title:Old Mr. Heffer'} -2026-01-31T02:30:18.082299Z [DEBUG] logger.debug: DEBUG: [API.Tidal] returning full_track_metadata keys: ['metadata', 'parsed', 'tags', 'lyrics'] -2026-01-31T02:30:34.965917Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T02:30:51.825988Z [DEBUG] logger.debug: DEBUG: ✓ Successfully processed 1 file(s) -2026-01-31T02:31:08.731514Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['https://tidal.com/album/573283'] -2026-01-31T02:31:25.630709Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T02:31:42.517404Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T02:31:59.428964Z [DEBUG] logger.debug: DEBUG: Processing URL: https://tidal.com/album/573283 -2026-01-31T02:32:16.310331Z [DEBUG] logger.debug: DEBUG: Provider tidal claimed https://tidal.com/album/573283 -2026-01-31T02:32:33.185648Z [DEBUG] logger.debug: DEBUG: Provider tidal matched URL but failed to download. Skipping direct fallback to avoid landing pages. -2026-01-31T02:32:50.063141Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T02:33:06.925293Z [DEBUG] download_file._run_impl: No downloads completed -2026-01-31T02:33:23.795760Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T02:33:40.702736Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=NoneType -2026-01-31T02:33:57.576167Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=rpi, provider=None, delete=False -2026-01-31T02:34:14.479753Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-01-31T02:34:31.370266Z [DEBUG] logger.debug: DEBUG: No resolution path matched. result type=NoneType -2026-01-31T02:34:48.231801Z [DEBUG] add_file._resolve_source: File path could not be resolved -2026-01-31T02:35:05.074465Z [DEBUG] logger.debug: DEBUG: [add-file] RESOLVED source: path=None, hash=N/A... -2026-01-31T02:35:21.962041Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['https://tidal.com/album/573283'] -2026-01-31T02:35:38.855495Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T02:35:55.744742Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T02:36:12.620046Z [DEBUG] logger.debug: DEBUG: Processing URL: https://tidal.com/album/573283 -2026-01-31T02:36:29.510687Z [DEBUG] logger.debug: DEBUG: Provider tidal claimed https://tidal.com/album/573283 -2026-01-31T02:36:46.399829Z [DEBUG] logger.debug: DEBUG: Provider tidal matched URL but failed to download. Skipping direct fallback to avoid landing pages. -2026-01-31T02:37:03.263652Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T02:37:20.136217Z [DEBUG] download_file._run_impl: No downloads completed -2026-01-31T02:47:58.313543Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce'] -2026-01-31T02:48:15.239418Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T02:48:32.132942Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:48:49.024151Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:49:05.935121Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:49:22.834182Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T02:49:39.730715Z [DEBUG] logger.debug: DEBUG: Processing URL: magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce -2026-01-31T02:49:56.666230Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:50:13.580110Z [DEBUG] logger.debug: DEBUG: Provider alldebrid claimed magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce -2026-01-31T02:50:30.485466Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:50:47.380343Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:51:04.300339Z [DEBUG] alldebrid.prepare_magnet: Failed to submit magnet to AllDebrid: AllDebrid API error: The auth apikey is invalid -2026-01-31T02:51:21.243679Z [DEBUG] logger.debug: DEBUG: Provider alldebrid handled URL without file output -2026-01-31T02:51:38.170472Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T02:51:55.086805Z [DEBUG] download_file._run_impl: No downloads completed -2026-01-31T02:52:11.993826Z [DEBUG] config_modal.save_all: ConfigModal scheduled save (changed=1) -2026-01-31T02:52:28.906008Z [DEBUG] config.save_config: Synced 31 entries to C:\Forgejo\Medios-Macina\medios.db (2 changed entries) -2026-01-31T02:52:45.801164Z [DEBUG] config.load_config: Loaded config from medios.db: providers=4 (alldebrid, soulseek, matrix, telegram), stores=2 (hydrusnetwork, debrid), mtime=2026-01-31T02:48:20.390764Z -2026-01-31T02:53:02.705696Z [DEBUG] config_modal._on_save_complete: Configuration saved (1 change(s)) to medios.db -2026-01-31T02:53:19.633636Z [DEBUG] config_modal._on_save_complete: ConfigModal saved 31 configuration entries (changed=1) -2026-01-31T02:53:36.536965Z [DEBUG] logger.debug: DEBUG: Status check: debug_enabled=True -2026-01-31T02:53:53.454571Z [DEBUG] logger.debug: DEBUG: MPV check OK: path=C:\Users\Admin\MPV\mpv.COM -2026-01-31T02:54:10.361035Z [DEBUG] logger.debug: DEBUG: StoreRegistry initialized. backends=['local', 'rpi'] -2026-01-31T02:54:27.260145Z [DEBUG] logger.debug: DEBUG: Hydrus network check: name=rpi, url=http://10.162.158.28:45899/ -2026-01-31T02:54:44.177272Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:55:01.096195Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:55:17.997911Z [DEBUG] logger.debug: DEBUG: Hydrus backend 'rpi' available: files=None -2026-01-31T02:55:34.930129Z [DEBUG] logger.debug: DEBUG: Hydrus network check: name=local, url=http://127.0.0.1:45869 -2026-01-31T02:55:51.831447Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:56:08.725121Z [DEBUG] logger.debug: DEBUG: -2026-01-31T02:56:25.631538Z [DEBUG] logger.debug: DEBUG: Hydrus backend 'local' available: files=None -2026-01-31T02:56:42.524271Z [DEBUG] logger.debug: DEBUG: Provider registries: providers=['tidal', 'alldebrid', 'bandcamp', 'file.io', 'helloprovider', 'internetarchive', 'libgen', 'loc', 'matrix', 'openlibrary', 'podcastindex', 'soulseek', 'telegram', 'torrent', 'vimm', 'youtube', 'ytdlp', '0x0'], search=['tidal', 'alldebrid', 'bandcamp', 'file.io', 'helloprovider', 'internetarchive', 'libgen', 'loc', 'matrix', 'openlibrary', 'podcastindex', 'soulseek', 'telegram', 'torrent', 'vimm', 'youtube', 'ytdlp', '0x0'], file=['tidal', 'alldebrid', 'bandcamp', 'file.io', 'helloprovider', 'internetarchive', 'libgen', 'loc', 'matrix', 'openlibrary', 'podcastindex', 'soulseek', 'telegram', 'torrent', 'vimm', 'youtube', 'ytdlp', '0x0'], metadata=['itunes', 'openlibrary', 'googlebooks', 'google', 'isbnsearch', 'musicbrainz', 'imdb', 'ytdlp', 'tidal'] -2026-01-31T02:56:59.433821Z [DEBUG] logger.debug: DEBUG: AllDebrid configured: api_key_present=True -2026-01-31T02:57:16.354005Z [DEBUG] logger.debug: DEBUG: AllDebrid client connected: base_url=https://api.alldebrid.com/v4 -2026-01-31T02:57:33.262253Z [DEBUG] logger.debug: DEBUG: Matrix check: homeserver=https://glowers.club, room_id=, validate=True -2026-01-31T02:57:50.189722Z [DEBUG] logger.debug: DEBUG: Cookies: resolved cookiefile=C:\Forgejo\Medios-Macina\cookies.txt -2026-01-31T02:58:07.094926Z [DEBUG] logger.debug: DEBUG: Status check completed: 9 checks recorded -2026-01-31T02:58:23.984422Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce'] -2026-01-31T02:58:40.879588Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-01-31T02:58:57.769633Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:59:14.676983Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:59:31.610969Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T02:59:48.545243Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-01-31T03:00:05.468339Z [DEBUG] logger.debug: DEBUG: Processing URL: magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce -2026-01-31T03:00:22.377757Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-01-31T03:00:39.283427Z [DEBUG] logger.debug: DEBUG: Provider alldebrid claimed magnet:?xt=urn:btih:FD307BFE7AECAF77851528F55E6C00802A390FEE&dn=Sonic+Frontiers%3A+Digital+Deluxe+Edition+%28v1.42+%2B+6+DLCs%2C+MULTi12%29+%5BFitGirl+Repack%2C+Selective+Download+-+from+12.9+GB%5D&tr=udp%3A%2F%2Fopentor.net%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.torrent.eu.org%3A451%2Fannounce&tr=udp%3A%2F%2Ftracker.qu.ax%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.fnix.net%3A6969%2Fannounce&tr=udp%3A%2F%2Fevan.im%3A6969%2Fannounce&tr=udp%3A%2F%2Fmartin-gebhardt.eu%3A25%2Fannounce&tr=https%3A%2F%2Fshahidrazi.online%3A443%2Fannounce&tr=http%3A%2F%2Fwegkxfcivgx.ydns.eu%3A80%2Fannounce&tr=http%3A%2F%2Flucke.fenesisu.moe%3A6969%2Fannounce&tr=udp%3A%2F%2Fextracker.dahrkael.net%3A6969%2Fannounce&tr=https%3A%2F%2Ftracker.alaskantf.com%3A443%2Fannounce&tr=https%3A%2F%2Ftracker.qingwa.pro%3A443%2Fannounce&tr=udp%3A%2F%2Ftracker.playground.ru%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.opentrackr.org%3A1337%2Fannounce&tr=http%3A%2F%2Ftracker.openbittorrent.com%3A80%2Fannounce&tr=udp%3A%2F%2Fopentracker.i2p.rocks%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.internetwarriors.net%3A1337%2Fannounce&tr=udp%3A%2F%2Ftracker.leechers-paradise.org%3A6969%2Fannounce&tr=udp%3A%2F%2Fcoppersurfer.tk%3A6969%2Fannounce&tr=udp%3A%2F%2Ftracker.zer0day.to%3A1337%2Fannounce -2026-01-31T03:00:56.186283Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:01:13.101650Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:01:29.944847Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-01-31T03:01:46.847975Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:02:03.780390Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:02:20.722204Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:02:37.686467Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:02:54.629823Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 13 result(s) -2026-01-31T03:03:11.552534Z [DEBUG] logger.debug: DEBUG: [alldebrid] Sent magnet 452385880 to AllDebrid for download -2026-01-31T03:03:28.471015Z [DEBUG] logger.debug: DEBUG: Provider alldebrid handled URL without file output -2026-01-31T03:03:45.401215Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-01-31T03:04:02.340538Z [DEBUG] download_file._run_impl: No downloads completed -2026-01-31T03:04:19.268324Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-01-31T03:04:36.178495Z [DEBUG] logger.debug: DEBUG: [add-file] Treating -path directory as destination: C:\Users\Admin\Downloads\sonic -2026-01-31T03:04:53.081995Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=list -2026-01-31T03:05:09.996953Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result is list with 13 items -2026-01-31T03:05:26.922170Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=C:\Users\Admin\Downloads\sonic, provider=None, delete=False -2026-01-31T03:05:43.856974Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-01-31T03:06:00.759605Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[1] PipeObject preview -2026-01-31T03:06:17.659227Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[2] PipeObject preview -2026-01-31T03:06:34.582661Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[3] PipeObject preview -2026-01-31T03:06:51.478918Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[4] PipeObject preview -2026-01-31T03:07:08.361667Z [DEBUG] logger.debug: DEBUG: [add-file] Skipping 8 additional piped item(s) in debug preview -2026-01-31T03:07:25.275447Z [DEBUG] logger.debug: DEBUG: No resolution path matched. result type=PipeObject -2026-01-31T03:07:42.183988Z [DEBUG] add_file._resolve_source: File path could not be resolved -2026-01-31T03:07:59.118354Z [DEBUG] logger.debug: DEBUG: [add-file] RESOLVED source: path=None, hash=N/A... -2026-01-31T03:12:02.961829Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-01-31T03:12:48.309103Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-01-31T03:13:05.258091Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:13:22.207331Z [DEBUG] logger.debug: DEBUG: -2026-01-31T03:13:39.166965Z [DEBUG] alldebrid.search: [alldebrid] Failed to list account magnets: AllDebrid API error: The auth apikey is invalid -2026-02-01T00:05:13.728816Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T04:23:03.090858Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T04:23:19.808488Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T04:23:36.282589Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-02-01T04:23:52.712154Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-02-01T04:40:52.223912Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T04:41:09.154528Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:41:26.087564Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:41:43.108223Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T04:42:00.084311Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T04:42:17.153684Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:42:33.940496Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:42:50.766611Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T04:43:07.583583Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T04:43:24.248810Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:43:40.777131Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:43:57.669799Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T04:45:05.508224Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['magnet:?xt=urn:btih:RO3NJTH7KWG3YIZM6ACTLJOFMF4IXK64'] -2026-02-01T04:45:22.402282Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T04:45:39.392199Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T04:45:56.042183Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T04:46:12.642265Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T04:46:29.352428Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-02-01T04:46:46.014474Z [DEBUG] logger.debug: DEBUG: Processing URL: magnet:?xt=urn:btih:RO3NJTH7KWG3YIZM6ACTLJOFMF4IXK64 -2026-02-01T04:47:02.617436Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T04:47:19.322141Z [DEBUG] logger.debug: DEBUG: Provider alldebrid claimed magnet:?xt=urn:btih:RO3NJTH7KWG3YIZM6ACTLJOFMF4IXK64 -2026-02-01T04:47:35.912588Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:47:52.591623Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:48:09.205544Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T04:48:25.895487Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:48:42.505354Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:48:59.158923Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 1 result(s) -2026-02-01T04:49:15.817590Z [DEBUG] logger.debug: DEBUG: [alldebrid] Sent magnet 453036517 to AllDebrid for download -2026-02-01T04:49:32.492568Z [DEBUG] logger.debug: DEBUG: Provider alldebrid handled URL without file output -2026-02-01T04:49:49.187120Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-02-01T04:50:05.787087Z [DEBUG] download_file._run_impl: No downloads completed -2026-02-01T04:50:22.440968Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T04:50:39.033859Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:50:55.684809Z [DEBUG] logger.debug: DEBUG: -2026-02-01T04:51:12.287393Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T05:38:20.685642Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T05:38:37.528797Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:38:54.568209Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:39:11.499306Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 0 result(s) -2026-02-01T05:39:28.345200Z [DEBUG] search_file._run_provider_search: No results found for query: 8 -2026-02-01T05:39:45.271491Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T05:40:02.052700Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:40:18.877026Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:40:35.473139Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T05:40:52.084907Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['https://releases.ubuntu.com/25.10/ubuntu-25.10-desktop-amd64.iso.torrent'] -2026-02-01T05:41:08.683766Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T05:41:25.407177Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T05:41:42.036819Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T05:41:58.700025Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T05:42:15.263270Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-02-01T05:42:31.663208Z [DEBUG] logger.debug: DEBUG: Processing URL: https://releases.ubuntu.com/25.10/ubuntu-25.10-desktop-amd64.iso.torrent -2026-02-01T05:42:48.151962Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=2 -2026-02-01T05:43:04.804447Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:43:21.350318Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:43:37.966291Z [DEBUG] logger.debug: DEBUG: Direct download: ubuntu-25.10-desktop-amd64.iso.torrent -2026-02-01T05:43:54.492442Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:49:42.813097Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:49:59.804166Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T05:50:16.612796Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:50:33.449999Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:50:50.429659Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:51:07.278581Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:51:24.198864Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 1 result(s) -2026-02-01T05:51:41.035358Z [DEBUG] logger.debug: DEBUG: [alldebrid] Sent magnet 453056143 to AllDebrid for download -2026-02-01T05:51:57.935933Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-02-01T05:52:14.768629Z [DEBUG] logger.debug: DEBUG: ✓ Successfully processed 0 file(s) and queued 1 magnet(s) -2026-02-01T05:54:32.519990Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T05:54:49.467693Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T05:55:06.466197Z [DEBUG] search_file.run: Backend all-debrid search failed: "Unknown store backend: all-debrid. Available: ['rpi', 'local']" -2026-02-01T05:55:23.429444Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'local' -2026-02-01T05:55:40.429236Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] Searching for: ubuntu -2026-02-01T05:55:57.414000Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:56:14.258918Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:56:31.269220Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:56:48.009593Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:57:04.463630Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] 0 result(s) -2026-02-01T05:57:20.977201Z [DEBUG] logger.debug: DEBUG: [search-file] 'local' -> 0 result(s) -2026-02-01T05:57:37.371749Z [DEBUG] logger.debug: DEBUG: [search-file] Searching 'rpi' -2026-02-01T05:57:53.793360Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] Searching for: ubuntu -2026-02-01T05:58:10.293366Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:58:26.713034Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:58:43.176453Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:58:59.805711Z [DEBUG] logger.debug: DEBUG: -2026-02-01T05:59:16.290292Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:rpi] 0 result(s) -2026-02-01T05:59:32.674044Z [DEBUG] logger.debug: DEBUG: [search-file] 'rpi' -> 0 result(s) -2026-02-01T05:59:49.098992Z [DEBUG] search_file.run: No results found -2026-02-01T06:00:05.551094Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T06:00:21.925543Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:00:38.435088Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:00:56.063587Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 4 result(s) -2026-02-01T06:01:12.572606Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T06:01:28.979743Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T06:01:45.482515Z [DEBUG] logger.debug: DEBUG: Applying row action for row 0 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:02:01.849708Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:02:18.396521Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:02:34.821622Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:02:51.186059Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 1 selected item(s) from table... -2026-02-01T06:03:07.738960Z [DEBUG] logger.debug: DEBUG: [download-file] Item 1/1: ['-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:03:24.187753Z [DEBUG] logger.debug: DEBUG: [download-file] Re-invoking download-file for selected item... -2026-02-01T06:03:40.685776Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:03:57.158246Z [DEBUG] download_file._run_impl: No url or piped items to download -2026-02-01T06:04:13.711114Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T06:04:30.184745Z [DEBUG] logger.debug: DEBUG: [add-file] Treating -path directory as destination: C:\Users\Admin\Downloads -2026-02-01T06:04:46.704198Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=NoneType -2026-02-01T06:05:03.237285Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=C:\Users\Admin\Downloads, provider=None, delete=False -2026-02-01T06:05:19.843731Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-02-01T06:05:36.556930Z [DEBUG] logger.debug: DEBUG: No resolution path matched. result type=NoneType -2026-02-01T06:05:52.985865Z [DEBUG] add_file._resolve_source: File path could not be resolved -2026-02-01T06:06:09.513156Z [DEBUG] logger.debug: DEBUG: [add-file] RESOLVED source: path=None, hash=N/A... -2026-02-01T06:06:26.348500Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-path', 'C:\\Users\\Admin\\Downloads'] -2026-02-01T06:06:43.235024Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:07:00.135479Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 1 selected item(s) from table... -2026-02-01T06:07:17.039344Z [DEBUG] logger.debug: DEBUG: [download-file] Item 1/1: ['-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:07:33.934576Z [DEBUG] logger.debug: DEBUG: [download-file] Re-invoking download-file for selected item... -2026-02-01T06:07:50.869276Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:08:07.749911Z [DEBUG] download_file._run_impl: No url or piped items to download -2026-02-01T06:33:46.595268Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T06:34:03.331255Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:34:20.063041Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:34:36.968421Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T06:39:50.111277Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T06:40:07.165210Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:40:24.108510Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:40:41.047193Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T06:40:58.000598Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T06:41:14.941587Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T06:41:31.848453Z [DEBUG] logger.debug: DEBUG: Applying row action for row 0 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:41:48.702300Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:42:05.614677Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:42:22.400732Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:42:39.062217Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 1 selected item(s) from table... -2026-02-01T06:42:55.757959Z [DEBUG] logger.debug: DEBUG: [download-file] Item 1/1: ['-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:43:12.525270Z [DEBUG] logger.debug: DEBUG: [download-file] Re-invoking download-file for selected item... -2026-02-01T06:43:29.285197Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:43:45.933657Z [DEBUG] download_file._run_impl: No url or piped items to download -2026-02-01T06:44:02.596721Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T06:44:19.232042Z [DEBUG] logger.debug: DEBUG: [add-file] Treating -path directory as destination: C:\Users\Admin\Downloads -2026-02-01T06:44:35.803491Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=NoneType -2026-02-01T06:45:34.001905Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T06:45:50.830664Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:46:07.717108Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:46:24.671507Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T06:46:41.535211Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T06:46:58.474762Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T06:47:15.425498Z [DEBUG] logger.debug: DEBUG: Applying row action for row 0 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:47:32.103024Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:47:48.783675Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:48:05.417641Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:48:21.975206Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 1 selected item(s) from table... -2026-02-01T06:48:38.558663Z [DEBUG] logger.debug: DEBUG: [download-file] Item 1/1: ['-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:48:55.169216Z [DEBUG] logger.debug: DEBUG: [download-file] Re-invoking download-file for selected item... -2026-02-01T06:49:11.791495Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:49:28.318339Z [DEBUG] download_file._run_impl: No url or piped items to download -2026-02-01T06:49:44.922882Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T06:50:01.491659Z [DEBUG] logger.debug: DEBUG: [add-file] Treating -path directory as destination: C:\Users\Admin\Downloads -2026-02-01T06:50:18.049203Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=NoneType -2026-02-01T06:50:34.573980Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=C:\Users\Admin\Downloads, provider=None, delete=False -2026-02-01T06:50:51.151427Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-02-01T06:51:07.770236Z [DEBUG] logger.debug: DEBUG: No resolution path matched. result type=NoneType -2026-02-01T06:51:48.993991Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T06:52:05.565435Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:52:22.277695Z [DEBUG] logger.debug: DEBUG: -2026-02-01T06:52:38.930595Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T06:52:55.552091Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T06:53:12.118174Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T06:53:28.790727Z [DEBUG] logger.debug: DEBUG: Applying row action for row 0 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:53:45.327237Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:54:01.987436Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:54:18.637408Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:54:35.221650Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 1 selected item(s) from table... -2026-02-01T06:54:51.872597Z [DEBUG] logger.debug: DEBUG: [download-file] Item 1/1: ['-url', 'alldebrid:magnet:453056143'] -2026-02-01T06:55:08.522116Z [DEBUG] logger.debug: DEBUG: [download-file] Re-invoking download-file for selected item... -2026-02-01T06:55:25.176164Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T06:55:41.975557Z [DEBUG] download_file._run_impl: No url or piped items to download -2026-02-01T06:55:58.717127Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T07:01:27.791652Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T07:01:44.750314Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:02:01.615307Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:02:18.332435Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T07:02:34.899263Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:02:51.552533Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:03:08.053494Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:03:24.820605Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:03:41.547457Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T07:03:58.225624Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T07:04:15.080542Z [DEBUG] logger.debug: DEBUG: Applying row action for row 0 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T07:04:31.831637Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T07:04:48.386018Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:453056143'] -2026-02-01T07:05:04.914412Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T07:05:21.471255Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:05:38.051683Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:05:54.492141Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:06:11.086311Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-02-01T07:06:27.636367Z [DEBUG] logger.debug: DEBUG: Processing URL: alldebrid:magnet:453056143 -2026-02-01T07:06:44.184703Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:07:00.670086Z [DEBUG] logger.debug: DEBUG: Provider alldebrid claimed alldebrid:magnet:453056143 -2026-02-01T07:07:17.283498Z [DEBUG] logger.debug: DEBUG: [download_items] Found magnet_id 453056143, downloading files directly -2026-02-01T07:07:33.848650Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:07:50.554843Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:08:07.114662Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:08:23.620063Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:08:40.149122Z [DEBUG] logger.debug: DEBUG: [alldebrid] Unlocked restricted link for ubuntu-25.10-desktop-amd64.iso -2026-02-01T07:08:56.672058Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:09:13.147916Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:09:29.607888Z [DEBUG] logger.debug: DEBUG: Direct download: ubuntu-25.10-desktop-amd64.iso -2026-02-01T07:09:46.253597Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:10:02.758497Z [DEBUG] logger.debug: DEBUG: ✓ Downloaded in 173.7s -2026-02-01T07:10:19.309317Z [DEBUG] logger.debug: DEBUG: [download_items] _download_magnet_by_id returned 1 -2026-02-01T07:10:35.893854Z [DEBUG] logger.debug: DEBUG: [download-file] Processing 0 piped item(s)... -2026-02-01T07:10:52.406210Z [DEBUG] logger.debug: DEBUG: ✓ Successfully processed 1 file(s) -2026-02-01T07:11:09.038280Z [DEBUG] logger.debug: DEBUG: [Store] Unknown store type 'debrid' -2026-02-01T07:11:25.581121Z [DEBUG] logger.debug: DEBUG: [add-file] Treating -path directory as destination: C:\Users\Admin\Downloads -2026-02-01T07:11:42.101434Z [DEBUG] logger.debug: DEBUG: [add-file] INPUT result type=dict -2026-02-01T07:11:58.648545Z [DEBUG] logger.debug: DEBUG: [add-file] PARSED args: location=C:\Users\Admin\Downloads, provider=None, delete=False -2026-02-01T07:12:15.119837Z [DEBUG] logger.debug: DEBUG: [add-file] PIPE item[0] PipeObject preview -2026-02-01T07:12:31.628095Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] get_file(hash=32e30d72ae47..., url=None) -2026-02-01T07:12:48.129021Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:13:04.670391Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:13:21.189393Z [DEBUG] logger.debug: DEBUG: [hydrusnetwork:local] get_file: falling back to url=http://127.0.0.1:45869/get_files/file?hash=32e30d72ae4798c633323a2684d94a11582bb03a6ab38d2b0d5ae5eabc5e577b&Hydrus-Client-API-Access-Key=95178b08e6ba3c57991e7b4e162c6efff1ce90c500005c6ebf8524122ed2486e -2026-02-01T07:13:37.692682Z [DEBUG] logger.debug: DEBUG: [add-file] RESOLVED source: path=C:\Users\Admin\AppData\Local\Temp\Medios-Macina\ubuntu-25.10-desktop-amd64.iso, hash=32e30d72ae4798c633323a2684d94a11582bb03a6ab38d2b0d5ae5eabc5e577b... -2026-02-01T07:13:54.207275Z [DEBUG] add_file._handle_local_export: Exporting to local path: C:\Users\Admin\Downloads -2026-02-01T07:14:10.869330Z [DEBUG] add_file._emit_pipe_object: Result (1 rows) -2026-02-01T07:24:10.136647Z [DEBUG] logger.debug: DEBUG: [table] state: restore_previous_result_table -2026-02-01T07:24:27.055053Z [DEBUG] logger.debug: DEBUG: [table] display_table: None -2026-02-01T07:24:43.959638Z [DEBUG] logger.debug: DEBUG: [table] current_stage_table: id=2533513637200 class=Table title='Alldebrid: *' table='alldebrid' rows=50 source='search-file' source_args=['-provider', 'alldebrid', '*'] no_choice=False preserve_order=False meta_keys=['provider', 'view'] -2026-02-01T07:25:00.818388Z [DEBUG] logger.debug: DEBUG: [table] last_result_table: id=2533513637200 class=Table title='Alldebrid: *' table='alldebrid' rows=50 source='search-file' source_args=['-provider', 'alldebrid', '*'] no_choice=False preserve_order=False meta_keys=['provider', 'view'] -2026-02-01T07:25:17.612177Z [DEBUG] logger.debug: DEBUG: [table] buffers: display_items=0 last_result_items=50 history=0 forward=1 last_selection=[] -2026-02-01T07:25:34.284226Z [DEBUG] logger.debug: DEBUG: [search-file] Calling alldebrid.search(filters={}) -2026-02-01T07:25:50.869811Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:26:07.410241Z [DEBUG] logger.debug: DEBUG: -2026-02-01T07:26:24.132737Z [DEBUG] logger.debug: DEBUG: [search-file] alldebrid -> 50 result(s) -2026-02-01T07:26:41.017739Z [DEBUG] search_file._run_provider_search: Error searching provider 'alldebrid': module 'SYS.pipeline' has no attribute 'set_last_result_table_preserve_history' -2026-02-01T07:26:57.935162Z [DEBUG] logger.debug: DEBUG: Traceback (most recent call last): - File "C:\Forgejo\Medios-Macina\cmdlet\search_file.py", line 418, in _run_provider_search - ctx.set_last_result_table_preserve_history(table, results_list) - ^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^^ -AttributeError: module 'SYS.pipeline' has no attribute 'set_last_result_table_preserve_history' - -2026-02-01T07:27:14.641323Z [DEBUG] logger.debug: DEBUG: Auto-inserting download-file after selection -2026-02-01T07:27:31.181302Z [DEBUG] logger.debug: DEBUG: Inserted auto stage before existing pipeline: ['download-file'] -2026-02-01T07:27:47.818774Z [DEBUG] logger.debug: DEBUG: Applying row action for row 8 -> ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:451673845'] -2026-02-01T07:28:04.421821Z [DEBUG] logger.debug: DEBUG: Replacing stage 0 ['download-file'] with row action ['download-file', '-provider', 'alldebrid', '-url', 'alldebrid:magnet:451673845'] -2026-02-01T07:28:21.056473Z [DEBUG] logger.debug: DEBUG: [download-file] run invoked with args: ['-provider', 'alldebrid', '-url', 'alldebrid:magnet:451673845'] -2026-02-01T07:28:37.886918Z [DEBUG] logger.debug: DEBUG: Starting download-file -2026-02-01T07:28:54.656389Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:29:11.420397Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:29:28.217562Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:29:45.060903Z [DEBUG] logger.debug: DEBUG: Output directory: C:\Users\Admin\AppData\Local\Temp\Medios-Macina -2026-02-01T07:30:01.922023Z [DEBUG] logger.debug: DEBUG: Processing URL: alldebrid:magnet:451673845 -2026-02-01T07:30:18.681576Z [DEBUG] alldebrid.url_patterns: [alldebrid] url_patterns loaded 0 cached host domains; total patterns=4 -2026-02-01T07:30:35.323006Z [DEBUG] logger.debug: DEBUG: Provider alldebrid claimed alldebrid:magnet:451673845 -2026-02-01T07:30:51.934937Z [DEBUG] logger.debug: DEBUG: [download_items] Found magnet_id 451673845, downloading files directly -2026-02-01T07:31:08.563245Z [DEBUG] logger.debug: DEBUG: