-- Logs begin at Tue 2025-11-04 22:28:53 GMT, end at Tue 2025-11-04 23:13:04 GMT. -- Nov 04 23:12:13 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:19 volumio kernel: usb 4-1: new SuperSpeed USB device number 2 using xhci-hcd Nov 04 23:12:19 volumio kernel: usb 4-1: New USB device found, idVendor=13fe, idProduct=6000, bcdDevice= 1.00 Nov 04 23:12:19 volumio kernel: usb 4-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 04 23:12:19 volumio kernel: usb 4-1: Product: Patriot Memory Nov 04 23:12:19 volumio kernel: usb 4-1: Manufacturer: Nov 04 23:12:19 volumio kernel: usb 4-1: SerialNumber: 070189AF36FB5C05 Nov 04 23:12:19 volumio kernel: usb-storage 4-1:1.0: USB Mass Storage device detected Nov 04 23:12:19 volumio kernel: scsi host0: usb-storage 4-1:1.0 Nov 04 23:12:20 volumio kernel: scsi 0:0:0:0: Direct-Access Patriot Memory PMAP PQ: 0 ANSI: 6 Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: [sda] 247463936 512-byte logical blocks: (127 GB/118 GiB) Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 2b 00 00 08 Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Nov 04 23:12:20 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Nov 04 23:12:26 volumio volumio[10290]: verbose: New Socket.io Connection to 192.168.8.105 from 192.168.8.222 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: Received Get System Info Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 23:12:26 volumio volumio[10290]: info: Discovery: Getting this device information Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 23:12:26 volumio volumio[10290]: info: Received Get System Info Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 23:12:26 volumio volumio[10290]: info: Discovery: Getting this device information Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:26 volumio volumio[10290]: info: Listing playlists Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::volumioGetQueue Nov 04 23:12:26 volumio volumio[10290]: info: CoreStateMachine::getQueue Nov 04 23:12:26 volumio volumio[10290]: info: CorePlayQueue::getQueue Nov 04 23:12:26 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 04 23:12:27 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand update Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand update took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatetrue Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 39ms Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 40 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 40 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 79ms Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 40ms Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 40ms Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: Nov 04 23:12:29 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 3 milliseconds Nov 04 23:12:29 volumio volumio[10290]: info: sendMpdCommand status took 4 milliseconds Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:29 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:29 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:29 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:29 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 59ms Nov 04 23:12:29 volumio volumio[10290]: info: ------------------------------ 59ms Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand rescan Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand rescan took 1 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 14ms Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 14 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 14 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 52ms Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 39ms Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 38ms Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: Nov 04 23:12:30 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 3 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:30 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:30 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:30 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:30 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:30 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 28ms Nov 04 23:12:30 volumio volumio[10290]: info: ------------------------------ 28ms Nov 04 23:12:30 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand rescan Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand rescan took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 67ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 67ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 67ms Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 68 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 67 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 108ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 41ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 41ms Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand rescan Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand rescan took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 16ms Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 16 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 16 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 54ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 39ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 40ms Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 27ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 28ms Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand rescan Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand rescan took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatetrue Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 16ms Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 16 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 16 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 0 milliseconds Nov 04 23:12:31 volumio volumio[10290]: info: sendMpdCommand status took 1 milliseconds Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:31 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:31 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:31 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 56ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 41ms Nov 04 23:12:31 volumio volumio[10290]: info: ------------------------------ 40ms Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:31 volumio volumio[10290]: info: Nov 04 23:12:31 volumio volumio[10290]: ---------------------------- MPD announces state update: update Nov 04 23:12:31 volumio volumio[10290]: info: ControllerMpd::getState Nov 04 23:12:31 volumio volumio[10290]: verbose: ControllerMpd::sendMpdCommand status Nov 04 23:12:32 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:32 volumio volumio[10290]: info: sendMpdCommand status took 3 milliseconds Nov 04 23:12:32 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:32 volumio volumio[10290]: info: sendMpdCommand status took 2 milliseconds Nov 04 23:12:32 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:32 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:32 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:32 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:32 volumio volumio[10290]: info: Command Router : Notfying DB Updatefalse Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::Close All Modals sent Nov 04 23:12:32 volumio volumio[10290]: verbose: ControllerMpd::parseState Nov 04 23:12:32 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:32 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:32 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:32 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:32 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:32 volumio volumio[10290]: info: ControllerMpd::pushState Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::servicePushState Nov 04 23:12:32 volumio volumio[10290]: info: CoreStateMachine::pushState Nov 04 23:12:32 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:32 volumio volumio[10290]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:32 volumio volumio[10290]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:32 volumio volumio[10290]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 04 23:12:32 volumio volumio[10290]: info: ------------------------------ 28ms Nov 04 23:12:32 volumio volumio[10290]: info: ------------------------------ 27ms Nov 04 23:12:34 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:38 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:40 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 04 23:12:40 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 04 23:12:42 volumio volumio[10290]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L QNAP-871.local. -m SMB3_11 Nov 04 23:12:42 volumio volumio[10290]: info: Error browsing device QNAP-871 for shares: Command failed: smbclient --no-pass --debuglevel=0 -L QNAP-871.local. -m SMB3_11 Nov 04 23:12:42 volumio volumio[10290]: Unable to initialize messaging context Nov 04 23:12:42 volumio volumio[10290]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:45 volumio volumio[10290]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:12:45 volumio volumio[10290]: Error: dns service error: unknown Nov 04 23:12:45 volumio volumio[10290]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Nov 04 23:12:45 volumio volumio[10290]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 04 23:12:45 volumio volumio[10290]: errorCode: -65537 Nov 04 23:12:45 volumio volumio[10290]: } Nov 04 23:12:45 volumio volumio[10290]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:12:46 volumio sudo[12408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 23:11 Nov 04 23:12:46 volumio sudo[12408]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:46 volumio sudo[12408]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:46 volumio volumio-remote-updater[822]: [2025-11-04 23:12:46] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 04 23:12:46 volumio volumio-remote-updater[822]: [2025-11-04 23:12:46] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 04 23:12:46 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 04 23:12:46 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 04 23:12:46 volumio systemd[1]: Started dynamicswap service. Nov 04 23:12:46 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 04 23:12:46 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 04 23:12:46 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Nov 04 23:12:46 volumio systemd[1]: Stopped Volumio Backend Module. Nov 04 23:12:46 volumio systemd[1]: Started Volumio Backend Module. Nov 04 23:12:46 volumio systemd[1]: Started dynamicswap service. Nov 04 23:12:46 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 04 23:12:46 volumio volumio[12418]: info: ------------------------------------------- Nov 04 23:12:46 volumio volumio[12418]: info: ----- Volumio3 ---- Nov 04 23:12:46 volumio volumio[12418]: info: ------------------------------------------- Nov 04 23:12:46 volumio volumio[12418]: info: ----- System startup ---- Nov 04 23:12:46 volumio volumio[12418]: info: ------------------------------------------- Nov 04 23:12:46 volumio volumio[12418]: info: MYVOLUMIO Environment detected Nov 04 23:12:46 volumio volumio[12418]: info: Plugin folders cleanup Nov 04 23:12:46 volumio volumio[12418]: info: Scanning into folder /volumio/app/plugins/ Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category audio_interface Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category miscellanea Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category music_service Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category plugins.json Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category system_controller Nov 04 23:12:46 volumio volumio[12418]: info: Scanning category user_interface Nov 04 23:12:46 volumio volumio[12418]: info: Scanning into folder /data/plugins/ Nov 04 23:12:46 volumio volumio[12418]: info: Plugin folders cleanup completed Nov 04 23:12:46 volumio volumio[12418]: info: ------------------------------------------- Nov 04 23:12:46 volumio volumio[12418]: info: ----- Core plugins startup ---- Nov 04 23:12:46 volumio volumio[12418]: info: ------------------------------------------- Nov 04 23:12:46 volumio volumio[12418]: info: Loading plugins from folder /volumio/app/plugins/ Nov 04 23:12:46 volumio volumio[12418]: info: Adding plugin upnp to MyMusic Plugins Nov 04 23:12:46 volumio volumio[12418]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 04 23:12:46 volumio volumio[12418]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 04 23:12:46 volumio volumio[12418]: info: Loading plugins from folder /data/plugins/ Nov 04 23:12:46 volumio volumio[12418]: info: Loading plugin "system"... Nov 04 23:12:46 volumio volumio[12418]: info: Loading plugin "appearance"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "network"... Nov 04 23:12:47 volumio volumio[12418]: info: Refreshing Cached IP Addresses Nov 04 23:12:47 volumio sudo[12446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 23:12:47 volumio sudo[12446]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:47 volumio sudo[12446]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:47 volumio sudo[12448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 23:12:47 volumio sudo[12448]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:47 volumio sudo[12448]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "services"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "alsa_controller"... Nov 04 23:12:47 volumio sudo[12461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 04 23:12:47 volumio sudo[12461]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "wizard"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "networkfs"... Nov 04 23:12:47 volumio volumio[12418]: info: Starting Udev Watcher for removable devices Nov 04 23:12:47 volumio sudo[12474]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.8.114:local/music/ /mnt/NAS/Qnap Nov 04 23:12:47 volumio sudo[12474]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:47 volumio volumio[12418]: info: Ignoring mount for partition: boot Nov 04 23:12:47 volumio volumio[12418]: info: Ignoring mount for partition: volumio Nov 04 23:12:47 volumio volumio[12418]: info: Ignoring mount for partition: volumio_data Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "volumio_command_line_client"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "upnp"... Nov 04 23:12:47 volumio volumio[12418]: info: [1762297967215] Starting Upmpd Daemon Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "my_music"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "mpd"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "upnp_browser"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "alarm-clock"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "airplay_emulation"... Nov 04 23:12:47 volumio volumio[12418]: info: Starting Shairport Sync Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "last_100"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "webradio"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "i2s_dacs"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "volumiodiscovery"... Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 04 23:12:47 volumio node[12418]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 04 23:12:47 volumio node[12418]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** For more information see Nov 04 23:12:47 volumio node[12418]: *** WARNING *** For more information see Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 04 23:12:47 volumio node[12418]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 04 23:12:47 volumio node[12418]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 04 23:12:47 volumio volumio[12418]: *** WARNING *** For more information see Nov 04 23:12:47 volumio node[12418]: *** WARNING *** For more information see Nov 04 23:12:47 volumio volumio[12418]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 04 23:12:47 volumio volumio[12418]: info: Discovery: Started advertising with name: Volumio Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "outputs"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "albumart"... Nov 04 23:12:47 volumio volumio[12418]: info: Plugin example_plugin is not enabled Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "inputs"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "updater_comm"... Nov 04 23:12:47 volumio volumio[12418]: info: Plugin mpdemulation is not enabled Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "rest_api"... Nov 04 23:12:47 volumio volumio[12418]: info: Loading plugin "websocket"... Nov 04 23:12:47 volumio volumio[12418]: info: Starting Socket.io Server version 2.3.0 Nov 04 23:12:47 volumio volumio[12418]: info: Loading i18n strings for locale en Nov 04 23:12:47 volumio volumio[12418]: Updating browse sources language Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 23:12:47 volumio volumio[12418]: Forking 3 albumart workers Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::initPlayerControls Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: Express server listening on port 3000 Nov 04 23:12:47 volumio volumio[12418]: [Metrics] WebUI: 0s 834.66ms Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::resetVolumioState Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::getcurrentVolume Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioRetrievevolume Nov 04 23:12:47 volumio volumio[12418]: info: Volumio Network Manager: Network status updated: 1 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 04 23:12:47 volumio volumio[12418]: info: Reloading queue from file Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::setRepeat null single undefined Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::pushState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::setRandom null Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::pushState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:47 volumio volumio[12418]: info: Setting Device type: Raspberry PI Nov 04 23:12:47 volumio volumio[12418]: info: VolumeController:: Volume=0 Mute =false Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::pushState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::updateTrackBlock Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrackBlock Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioRetrievevolume Nov 04 23:12:47 volumio volumio[12418]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 04 23:12:47 volumio volumio[12418]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d04171 Nov 04 23:12:47 volumio volumio[12418]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 04 23:12:47 volumio volumio[12418]: info: VolumeController:: Volume=0 Mute =false Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::pushState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioPushState Nov 04 23:12:47 volumio volumio[12418]: verbose: New Socket.io Connection to 192.168.8.105 from 192.168.8.222 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: Received Get System Info Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 23:12:47 volumio volumio[12418]: info: Discovery: Getting this device information Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetVisibleSources Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 04 23:12:47 volumio volumio[12418]: info: Received Get System Info Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 04 23:12:47 volumio volumio[12418]: info: Discovery: Getting this device information Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:47 volumio volumio[12418]: info: Listing playlists Nov 04 23:12:47 volumio volumio[12418]: info: CoreCommandRouter::volumioGetQueue Nov 04 23:12:47 volumio volumio[12418]: info: CoreStateMachine::getQueue Nov 04 23:12:47 volumio volumio[12418]: Starting albumart workers Nov 04 23:12:47 volumio volumio[12418]: info: CorePlayQueue::getQueue Nov 04 23:12:47 volumio volumio[12418]: Starting albumart workers Nov 04 23:12:47 volumio volumio[12418]: Starting albumart workers Nov 04 23:12:48 volumio volumio[12418]: info: Discovery: adding 4331a62f-6dba-488f-a80b-1cbbc30d49e4 Nov 04 23:12:48 volumio volumio[12418]: info: Discovery: Found device Volumio Nov 04 23:12:48 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:48 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:48 volumio volumio[12418]: info: Discovery: this is already registered, 4331a62f-6dba-488f-a80b-1cbbc30d49e4 Nov 04 23:12:48 volumio volumio[12418]: info: Discovery: Found device Volumio Nov 04 23:12:48 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:48 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:50 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:50 volumio sudo[12461]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:51 volumio volumio-remote-updater[822]: [2025-11-04 23:12:51] [connect] Successful connection Nov 04 23:12:51 volumio volumio-remote-updater[822]: [2025-11-04 23:12:51] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762297971 101 Nov 04 23:12:51 volumio volumio[12418]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Nov 04 23:12:54 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:56 volumio volumio[12418]: info: CoreCommandRouter::volumioGetState Nov 04 23:12:56 volumio volumio[12418]: info: CorePlayQueue::getTrack 0 Nov 04 23:12:57 volumio sudo[12570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 04 23:12:57 volumio sudo[12570]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:57 volumio sudo[12570]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:57 volumio sudo[12572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 04 23:12:57 volumio sudo[12572]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:57 volumio sudo[12572]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:57 volumio sudo[12576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 04 23:12:57 volumio sudo[12576]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 04 23:12:57 volumio sudo[12576]: pam_unix(sudo:session): session closed for user root Nov 04 23:12:57 volumio volumio[12418]: info: Upmpdcli Daemon Started Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::volumioGetBrowseSources Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:12:59 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 04 23:13:03 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 04 23:13:04 volumio volumio[12418]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 04 23:13:04 volumio volumio[12418]: verbose: ControllerMpd::sendMpdCommand update Nov 04 23:13:04 volumio volumio[12418]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:13:04 volumio volumio[12418]: TypeError: Cannot read property 'then' of undefined Nov 04 23:13:04 volumio volumio[12418]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Nov 04 23:13:04 volumio volumio[12418]: at ControllerMpd.updateDb (/volumio/app/plugins/music_service/mpd/index.js:1899:15) Nov 04 23:13:04 volumio volumio[12418]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Nov 04 23:13:04 volumio volumio[12418]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1091:26) Nov 04 23:13:04 volumio volumio[12418]: at Socket.emit (events.js:315:20) Nov 04 23:13:04 volumio volumio[12418]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 04 23:13:04 volumio volumio[12418]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 04 23:13:04 volumio volumio[12418]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 04 23:13:04 volumio sudo[12634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-04 23:12 Nov 04 23:13:04 volumio sudo[12634]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"