Feb 17 20:36:44 volumio volumio[1346]: error: error
Feb 17 20:36:44 volumio volumio[1346]: error: error
Feb 17 20:36:44 volumio volumio[1346]: error: error
Feb 17 20:36:58 volumio volumio[1346]: info: Preload queue cleared
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioReplaceandPlayItems
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::ClearQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::stop
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::updateTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 38
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::serviceStop
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 38
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::serviceStop
Feb 17 20:36:58 volumio volumio[1346]: info: [1771328218974] ControllerUPNPBrowser::stop
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand stop
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::clearPlayQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::saveQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::addQueueItems
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::addQueueItems
Feb 17 20:36:58 volumio volumio[1346]: info: Preload queue cleared
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9103
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i9103
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4940
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1157$*i4940
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16928
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16928
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i15386
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i15386
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i5783
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i5783
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16830
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16830
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i17422
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i17422
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16494
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1248$*i16494
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1249$*i14244
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1249$*i14244
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1249$*i13744
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1139$f1246$f1249$*i13744
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1664$*i2560
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1664$*i2560
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1665$*i12830
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1665$*i12830
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i14702
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i14702
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i14313
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i14313
Feb 17 20:36:58 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:58.976+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:58 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:58.977+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.210:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::saveQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::updateTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPlay
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::play index 13
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::addQueueItems
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::addQueueItems
Feb 17 20:36:58 volumio volumio[1346]: info: Preload queue cleared
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i16350
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i16350
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i15895
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1662$f1719$*i15895
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i519
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i519
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i1306
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i1306
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i1525
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1502$f1988$f2023$*i1525
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i5209
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i5209
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i5106
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i5106
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i3449
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f40$f49$*i3449
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i7265
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i7265
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i9967
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i9967
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i6914
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i6914
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i10903
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f159$*i10903
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i4722
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i4722
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i7568
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i7568
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i7456
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i7456
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i10944
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i10944
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i6512
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f1$f155$f156$*i6512
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i9965
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i9965
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i6915
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i6915
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i4267
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1368$*i4267
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i9322
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i9322
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i3113
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i3113
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i6290
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.222:9791/55f0a59f-0838-4b43-b645-693172e035ff/upnp.org-ContentDirectory-1/control@0$folders$f404$f1329$f1343$f1367$f1369$*i6290
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.210:9791/10d8b6e1-33ef-492c-8dff-2d0727d7c64c/upnp.org-ContentDirectory-1/control@0$folders$f395$f396$f453$*i14583
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.210:9791/10d8b6e1-33ef-492c-8dff-2d0727d7c64c/upnp.org-ContentDirectory-1/control@0$folders$f395$f396$f453$*i14583
Feb 17 20:36:58 volumio volumio[1346]: info: Adding Item to queue: upnp/http://192.168.0.210:9791/10d8b6e1-33ef-492c-8dff-2d0727d7c64c/upnp.org-ContentDirectory-1/control@0$folders$f395$f396$f453$*i14195
Feb 17 20:36:58 volumio volumio[1346]: info: Using cached record of: upnp/http://192.168.0.210:9791/10d8b6e1-33ef-492c-8dff-2d0727d7c64c/upnp.org-ContentDirectory-1/control@0$folders$f395$f396$f453$*i14195
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::stop
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::saveQueue
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::play index undefined
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::updateTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrackBlock
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::startPlaybackTimer
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:58 volumio volumio[1346]: info: [1771328218990] ControllerUPNPBrowser::clearAddPlayTrack
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand stop
Feb 17 20:36:58 volumio volumio[1346]: info:
Feb 17 20:36:58 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand stop took 20 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand stop took 4 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand clear
Feb 17 20:36:58 volumio volumio[1346]: info:
Feb 17 20:36:58 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:58 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:58 volumio volumio[1346]: info:
Feb 17 20:36:58 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:58 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:58 volumio volumio[1346]: info:
Feb 17 20:36:58 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:58 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand status took 3 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand clear took 2 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac"
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:58 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:58 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:58 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:58 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:58 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: No code
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService stop
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: No code
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.005+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.006+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.006+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.006+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.006+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.007+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_STOPPED positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.007+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=サタデイ・イン・ザ・パーク
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 19ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 19ms
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: error: updateQueue error: null
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 18ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 18 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split')
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 21ms
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac"
Feb 17 20:36:59 volumio volumio[1346]: error: updateQueue error: null
Feb 17 20:36:59 volumio volumio[1346]: error: updateQueue error: null
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 222ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 221ms
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand add "http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac" took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand play
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 1ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand play took 0 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 1ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 1ms
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 9 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 10 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 10 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus stop
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 10ms
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 2 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.277+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.277+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.278+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.278+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.278+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.278+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.279+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.279+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 20ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 20ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 10 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 10 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 10 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.286+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.286+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.287+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 18ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 18ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 8 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"7-L-R*2024-192000.flac","artist":null,"album":null,"uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.292+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.292+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=0 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.293+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.293+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title=7-L-R*2024-192000.flac
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 22ms
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces system playlist update
Feb 17 20:36:59 volumio volumio[1346]: info: Ignoring MPD Status Update
Feb 17 20:36:59 volumio volumio[1346]: info:
Feb 17 20:36:59 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 2ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 1ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 1ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 1 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2266 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2550 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.496+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 7ms
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 7ms
Feb 17 20:36:59 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 6 milliseconds
Feb 17 20:36:59 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:36:59 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:36:59 volumio volumio[1346]: verbose: STATE SERVICE {"status":"play","position":0,"seek":142,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"3087 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:36:59 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState stateService play
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus play
Feb 17 20:36:59 volumio volumio[1346]: info: Received an update from plugin. extracting info from payload
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.500+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.500+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.500+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PLAYING positionMs=250 volume=
Feb 17 20:36:59 volumio volumio5-onboarding[1555]: time=2026-02-17T20:36:59.500+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:36:59 volumio volumio[1346]: info: ------------------------------ 10ms
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:36:59 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:37:27 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories...
Feb 17 20:37:27 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully.
Feb 17 20:37:27 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories.
Feb 17 20:37:27 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully.
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioPause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::servicePause
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::servicePause
Feb 17 20:37:31 volumio volumio[1346]: info: [1771328251333] ControllerUPNPBrowser::pause
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand pause
Feb 17 20:37:31 volumio volumio[1346]: info:
Feb 17 20:37:31 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand pause took 1 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:37:31 volumio volumio[1346]: info:
Feb 17 20:37:31 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:37:31 volumio volumio[1346]: info:
Feb 17 20:37:31 volumio volumio[1346]: ---------------------------- MPD announces state update: player
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::getState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand status
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand status took 1 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 0 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseState
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:37:31 volumio volumio[1346]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32746,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4830 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:37:31 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState stateService pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.337+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PAUSED positionMs=32010 volume=
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.337+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:37:31 volumio volumio[1346]: info: ------------------------------ 3ms
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 2 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: info: sendMpdCommand playlistinfo took 3 milliseconds
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:37:31 volumio volumio[1346]: verbose: ControllerMpd::parseTrackInfo
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:37:31 volumio volumio[1346]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32746,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4830 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:37:31 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState stateService pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer
Feb 17 20:37:31 volumio volumio[1346]: info: ControllerMpd::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::servicePushState
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getTrack 13
Feb 17 20:37:31 volumio volumio[1346]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":32746,"duration":237,"samplerate":"192 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"4830 Kbps","isStreaming":false,"title":"サタデイ・イン・ザ・パーク ","artist":"Chicago","album":"CHICAGO V","uri":"http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac","trackType":"flac"}
Feb 17 20:37:31 volumio volumio[1346]: verbose: CURRENT POSITION 13
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState stateService pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::syncState currentStatus pause
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::pushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioPushState
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::stPlaybackTimer
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.340+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PAUSED positionMs=32010 volume=
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.340+09:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" state=STATUS_PAUSED positionMs=32010 volume=
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.340+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:37:31 volumio volumio5-onboarding[1555]: time=2026-02-17T20:37:31.340+09:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.19:49156 @ 0x32489f0" id=http://192.168.0.222:9790/minimserver/*/Multimedia/music*20station*20Y/DVD-AUDIO/CHICAGO*e2*85*a4*e3*80*80FLAC/7-L-R*2024-192000.flac title="サタデイ・イン・ザ・パーク "
Feb 17 20:37:31 volumio volumio[1346]: info: ------------------------------ 6ms
Feb 17 20:37:31 volumio volumio[1346]: info: ------------------------------ 6ms
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CoreCommandRouter::volumioGetQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CoreStateMachine::getQueue
Feb 17 20:37:31 volumio volumio[1346]: info: CorePlayQueue::getQueue
Feb 17 20:37:37 volumio volumio[1346]: info: CoreCommandRouter::volumioGetState
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: info: Retrieving Cloud Streaming UI
Feb 17 20:37:40 volumio volumio[1346]: info: Getting Tidal Cloud Configuration
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: info: Getting Qobuz Cloud Configuration
Feb 17 20:37:40 volumio volumio[1346]: info: Asking plugin for UI Config
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: info: Getting Spotify Cloud Configuration
Feb 17 20:37:40 volumio volumio[1346]: info: Asking plugin for UI Config
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: info: Saving Spotify Acccount
Feb 17 20:37:40 volumio volumio[1346]: info: Got Tidal Cloud Configuration
Feb 17 20:37:40 volumio volumio[1346]: info: Got it
Feb 17 20:37:40 volumio volumio[1346]: info: Got it
Feb 17 20:37:40 volumio volumio[1346]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::getUIConfigOnPlugin
Feb 17 20:37:40 volumio volumio[1346]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave')
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::volumioGetBrowseSources
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 17 20:37:40 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Feb 17 20:37:43 volumio volumio[1346]: error: error
Feb 17 20:37:43 volumio volumio[1346]: error: error
Feb 17 20:37:43 volumio volumio[1346]: error: error
Feb 17 20:37:44 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 17 20:37:48 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 17 20:37:50 volumio nmbd[1225]: [2026/02/17 20:37:50.264382, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response)
Feb 17 20:37:50 volumio nmbd[1225]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.45 for name WORKGROUP<1d>.
Feb 17 20:37:50 volumio nmbd[1225]: This response was from IP 192.168.0.17, reporting an IP address of 192.168.0.17.
Feb 17 20:37:52 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 17 20:37:54 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Feb 17 20:37:54 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Feb 17 20:37:56 volumio volumio[1346]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L HS-210N.local. -m SMB3_11
Feb 17 20:37:56 volumio volumio[1346]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Feb 17 20:37:59 volumio volumio[1346]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 17 20:37:59 volumio volumio[1346]: Error: dns service error: unknown
Feb 17 20:37:59 volumio volumio[1346]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30)
Feb 17 20:37:59 volumio volumio[1346]: at MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) {
Feb 17 20:37:59 volumio volumio[1346]: errorCode: -65537
Feb 17 20:37:59 volumio volumio[1346]: }
Feb 17 20:37:59 volumio volumio[1346]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 17 20:37:59 volumio sudo[5543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 20:36'
Feb 17 20:37:59 volumio sudo[5543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)"
NAME="Raspbian GNU/Linux"
VERSION_ID="12"
VERSION="12 (bookworm)"
VERSION_CODENAME=bookworm
ID=raspbian
ID_LIKE=debian
HOME_URL="http://www.raspbian.org/"
SUPPORT_URL="http://www.raspbian.org/RaspbianForums"
BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs"
VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a"
VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd"
VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2"
VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026"
VOLUMIO_VERSION="4.096"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"