-- Logs begin at Sun 2025-10-05 18:29:10 +08, end at Mon 2025-10-27 17:08:48 +08. -- Oct 27 17:07:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 17:07:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 17:07:14 rivo volumio[3576]: info: Discovery: Getting this device information Oct 27 17:07:14 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 17:07:19 rivo vtcs[4359]: [close:85] Entering Oct 27 17:07:19 rivo vtcs[4359]: [close:100] Exiting Oct 27 17:07:19 rivo vtcs[4359]: [2025-10-27 17:07:19.017] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: Pushing metadata Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: MCU Signalled Playback Active Oct 27 17:07:19 rivo vtcs[4359]: [close:85] Entering Oct 27 17:07:19 rivo vtcs[4359]: [close:100] Exiting Oct 27 17:07:19 rivo volumio[3576]: info: Pushing metadata Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:19 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo vtcs[4359]: [close:85] Entering Oct 27 17:07:19 rivo vtcs[4359]: [close:100] Exiting Oct 27 17:07:19 rivo vtcs[4359]: [open:50] Opening with sample rate: 44100, type: 0 Oct 27 17:07:19 rivo vtcs[4359]: [start:106] Entering Oct 27 17:07:19 rivo vtcs[4359]: [start:113] Reopening PCM device... Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:19 rivo vtcs[4359]: [start:115] PCM opened successfully. Oct 27 17:07:19 rivo vtcs[4359]: [start:120] Exiting Oct 27 17:07:19 rivo vtcs[4359]: [feedThread:223] Entering Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:19 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:19 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:19 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:20 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:07:20 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:07:20 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:07:20 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:07:20 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:20 rivo volumio[3576]: info: Signalling Playback active due to playback status change Oct 27 17:07:20 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:07:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 17:07:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 17:07:44 rivo volumio[3576]: info: Discovery: Getting this device information Oct 27 17:07:44 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:07:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 17:08:03 rivo vtcs[4359]: [pause:147] Entering Oct 27 17:08:03 rivo volumio[3576]: info: CoreCommandRouter::servicePushState Oct 27 17:08:03 rivo volumio[3576]: info: CoreStateMachine::pushState Oct 27 17:08:03 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 27 17:08:03 rivo volumio[3576]: info: CoreCommandRouter::volumioPushState Oct 27 17:08:03 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:08:03 rivo volumio[3576]: info: Updating RAAT Signal Path Oct 27 17:08:03 rivo vtcs[4359]: [feedThread:276] Exiting Oct 27 17:08:03 rivo vtcs[4359]: [pause:161] Exiting Oct 27 17:08:03 rivo volumio[3576]: info: MCU Signalled Playback Inactive Oct 27 17:08:08 rivo vtcs[4359]: [2025-10-27 17:08:08] [error] handle_read_frame error: asio.ssl.stream:1 (stream truncated) Oct 27 17:08:08 rivo vtcs[4359]: [2025-10-27 17:08:08] [info] asio async_shutdown error: asio.ssl.stream:1 (stream truncated) Oct 27 17:08:10 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 27 17:08:10 rivo volumio[3576]: info: CURURI: music-library Oct 27 17:08:10 rivo volumio[3576]: error: Failed LSINFO: null Oct 27 17:08:10 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:13 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 27 17:08:13 rivo volumio[3576]: info: CURURI: music-library Oct 27 17:08:13 rivo volumio[3576]: error: Failed LSINFO: null Oct 27 17:08:13 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 17:08:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 17:08:14 rivo volumio[3576]: info: Discovery: Getting this device information Oct 27 17:08:14 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:08:14 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 17:08:19 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:19 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:22 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:22 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:29 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 27 17:08:29 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 27 17:08:29 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:31 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Oct 27 17:08:31 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/281492878 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/288516417 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/553 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/349631185 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/60312031 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/319297535 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/89588966 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/9890173 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/8439085 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/29809769 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/193574245 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/29809772 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/313530663 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/21651812 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/360479505 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/5120304 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/207194075 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/280229897 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/325318855 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/106258685 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/17178313 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/210687227 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/210687228 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/266227598 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/70555063 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/70555064 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/69965336 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/69965335 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/84450855 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/69965330 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/160286041 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/160286038 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/68733629 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/93158823 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/16944109 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/194618177 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/82635219 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/150804540 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/151573722 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/151573723 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/105048155 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/327345403 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/313815390 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/95441083 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/5761228 Oct 27 17:08:31 rivo volumio[3576]: info: Preloading song: tidal://song/20411740 Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/281492878 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/288516417 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/553 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/349631185 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/60312031 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/319297535 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/89588966 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/9890173 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: explodeTIDALUri took 296 milliseconds Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/8439085 in service mpd Oct 27 17:08:31 rivo volumio[3576]: error: scanFolder - failure to stat '/mnt/tidal://song/8439085' Oct 27 17:08:31 rivo volumio[3576]: info: explodeTIDALUri took 374 milliseconds Oct 27 17:08:31 rivo volumio[3576]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [288516417] not found"} Oct 27 17:08:31 rivo volumio[3576]: error: Commandrouter: Cannot explode uri tidal://song/288516417 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [288516417] not found"} Oct 27 17:08:31 rivo volumio[3576]: info: Exploding uri tidal://song/29809769 in service tidal Oct 27 17:08:31 rivo volumio[3576]: info: explodeTIDALUri took 293 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/193574245 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 293 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 351 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/29809772 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/313530663 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 312 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 301 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/21651812 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/360479505 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 741 milliseconds Oct 27 17:08:32 rivo volumio[3576]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [281492878] not found"} Oct 27 17:08:32 rivo volumio[3576]: error: Commandrouter: Cannot explode uri tidal://song/281492878 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [281492878] not found"} Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 296 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/5120304 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/207194075 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 344 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/280229897 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 309 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 299 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/325318855 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 295 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/106258685 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 279 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/17178313 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 277 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/210687227 in service mpd Oct 27 17:08:32 rivo volumio[3576]: error: scanFolder - failure to stat '/mnt/tidal://song/210687227' Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 294 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/210687228 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 299 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/266227598 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/70555063 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 304 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: explodeTIDALUri took 298 milliseconds Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/70555064 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/69965336 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/69965335 in service mpd Oct 27 17:08:32 rivo volumio[3576]: error: scanFolder - failure to stat '/mnt/tidal://song/69965335' Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/84450855 in service tidal Oct 27 17:08:32 rivo volumio[3576]: info: Exploding uri tidal://song/69965330 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/160286041 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 348 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 402 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 455 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 557 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 309 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/160286038 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/68733629 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/93158823 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/16944109 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 411 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/194618177 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 321 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 374 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/82635219 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/150804540 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/151573722 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 299 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 388 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/151573723 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 459 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 361 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/105048155 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 308 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 275 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/327345403 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 317 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/313815390 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 296 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/95441083 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 281 milliseconds Oct 27 17:08:33 rivo volumio[3576]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [151573722] not found"} Oct 27 17:08:33 rivo volumio[3576]: error: Commandrouter: Cannot explode uri tidal://song/151573722 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [151573722] not found"} Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/5761228 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 297 milliseconds Oct 27 17:08:33 rivo volumio[3576]: error: TIDAL Browse Error: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [151573723] not found"} Oct 27 17:08:33 rivo volumio[3576]: error: Commandrouter: Cannot explode uri tidal://song/151573723 from service tidal: failed to get track: got 404: {"status":404,"subStatus":2001,"userMessage":"Track [151573723] not found"} Oct 27 17:08:33 rivo volumio[3576]: info: Exploding uri tidal://song/20411740 in service tidal Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 283 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 367 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 275 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 276 milliseconds Oct 27 17:08:33 rivo volumio[3576]: info: explodeTIDALUri took 277 milliseconds Oct 27 17:08:34 rivo volumio[3576]: info: explodeTIDALUri took 307 milliseconds Oct 27 17:08:43 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:43 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:44 rivo volumio[3576]: info: Preload queue cleared Oct 27 17:08:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 27 17:08:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 27 17:08:44 rivo volumio[3576]: info: Discovery: Getting this device information Oct 27 17:08:44 rivo volumio[3576]: info: CoreCommandRouter::volumioGetState Oct 27 17:08:44 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 27 17:08:47 rivo volumio[3576]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 27 17:08:47 rivo volumio[3576]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 17:08:47 rivo volumio[3576]: TypeError: Cannot read property 'length' of undefined Oct 27 17:08:47 rivo volumio[3576]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 27 17:08:47 rivo volumio[3576]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 27 17:08:47 rivo volumio[3576]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 27 17:08:47 rivo volumio[3576]: at Parser.emit (events.js:400:28) Oct 27 17:08:47 rivo volumio[3576]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 27 17:08:47 rivo volumio[3576]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 27 17:08:47 rivo volumio[3576]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 27 17:08:47 rivo volumio[3576]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 27 17:08:47 rivo volumio[3576]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 27 17:08:47 rivo volumio[3576]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 27 17:08:47 rivo volumio[3576]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 27 17:08:47 rivo volumio[3576]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 27 17:08:47 rivo volumio[3576]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 27 17:08:47 rivo volumio[3576]: at IncomingMessage.emit (events.js:412:35) Oct 27 17:08:47 rivo volumio[3576]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 27 17:08:47 rivo volumio[3576]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 27 17:08:47 rivo volumio[3576]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 27 17:08:48 rivo sudo[8721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-27 17:07 Oct 27 17:08:48 rivo sudo[8721]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:43:09 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="fc7d09bc07ffd188bff4808d8f79c746"