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'