Apr 12 12:45:15 volumio kernel: usb 1-1.3: new high-speed USB device number 3 using xhci_hcd Apr 12 12:45:15 volumio kernel: usb 1-1.3: New USB device found, idVendor=14cd, idProduct=125d, bcdDevice= 1.00 Apr 12 12:45:15 volumio kernel: usb 1-1.3: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Apr 12 12:45:15 volumio kernel: usb 1-1.3: Product: Mass Storage Device Apr 12 12:45:15 volumio kernel: usb 1-1.3: Manufacturer: Generic Apr 12 12:45:15 volumio kernel: usb 1-1.3: SerialNumber: 125D20140310 Apr 12 12:45:15 volumio kernel: usb-storage 1-1.3:1.0: USB Mass Storage device detected Apr 12 12:45:15 volumio kernel: scsi host0: usb-storage 1-1.3:1.0 Apr 12 12:45:16 volumio kernel: scsi 0:0:0:0: Direct-Access Mass Storage Device PQ: 0 ANSI: 0 CCS Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] 65536000 512-byte logical blocks: (33.6 GB/31.3 GiB) Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 03 00 00 00 Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 12 12:45:16 volumio kernel: sda: sda1 Apr 12 12:45:16 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Apr 12 12:45:16 volumio volumio[1409]: info: Mounting Device 4002-136D Apr 12 12:45:16 volumio sudo[21021]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/4002-136D -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 12 12:45:16 volumio sudo[21021]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 12 12:45:16 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Apr 12 12:45:16 volumio sudo[21021]: pam_unix(sudo:session): session closed for user root Apr 12 12:45:16 volumio volumio[1409]: info: Scanning new location : "USB/4002-136D" Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: info: Apr 12 12:45:16 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:16 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: info: Apr 12 12:45:16 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:16 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: info: Apr 12 12:45:16 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:16 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:16 volumio volumio[1409]: info: sendMpdCommand status took 4 milliseconds Apr 12 12:45:16 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: info: Command Router : Notfying DB Updatetrue Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand status took 118 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand status took 117 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand status took 117 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand status took 117 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand status took 117 milliseconds Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: info: Command Router : Notfying DB Updatetrue Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::Close All Modals sent Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: info: Command Router : Notfying DB Updatetrue Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::Close All Modals sent Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 108 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 62 milliseconds Apr 12 12:45:17 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 26 milliseconds Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:17 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:17 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:17 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:17 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:17 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:17 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:17 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:17 volumio volumio[1409]: info: ------------------------------ 264ms Apr 12 12:45:17 volumio volumio[1409]: info: ------------------------------ 262ms Apr 12 12:45:17 volumio volumio[1409]: info: ------------------------------ 261ms Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 9d032e68) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): Filesystem has been set read-only Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 05816f13) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start c9c0cfae) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 7b9f887d) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start b7de28c0) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 2f5c69bb) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start e31dc906) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 1e58eb4c) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start d2194bf1) Apr 12 12:45:20 volumio kernel: FAT-fs (sda1): error, fat_get_cluster: invalid start cluster (i_pos 0, start 6825eb6e) Apr 12 12:45:20 volumio volumio[1409]: info: MPD Database updated - AlbumList cache refreshed Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: database Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: MPD Database updated - AlbumList cache refreshed Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: database Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: MPD Database updated - AlbumList cache refreshed Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: database Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: Apr 12 12:45:20 volumio volumio[1409]: ---------------------------- MPD announces state update: update Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::getState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand status Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 61 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 60 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 61 milliseconds Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: info: Command Router : Notfying DB Updatefalse Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::Close All Modals sent Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 134 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 133 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 133 milliseconds Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: info: Command Router : Notfying DB Updatefalse Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::Close All Modals sent Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 168 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 168 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand status took 168 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 66 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 66 milliseconds Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: info: Command Router : Notfying DB Updatefalse Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::Close All Modals sent Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseState Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 195ms Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 196ms Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 74 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 75 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 37 milliseconds Apr 12 12:45:20 volumio volumio[1409]: info: sendMpdCommand playlistinfo took 38 milliseconds Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: verbose: ControllerMpd::parseTrackInfo Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ControllerMpd::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::servicePushState Apr 12 12:45:20 volumio volumio[1409]: info: CoreStateMachine::pushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: CoreCommandRouter::volumioPushState Apr 12 12:45:20 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:20 volumio volumio[1409]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 240ms Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 241ms Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 239ms Apr 12 12:45:20 volumio volumio[1409]: info: ------------------------------ 241ms Apr 12 12:45:27 volumio volumio[1409]: verbose: New Socket.io Connection to 192.168.31.238 from 192.168.31.167 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Apr 12 12:45:29 volumio volumio[1409]: verbose: New Socket.io Connection to 192.168.31.238 from 192.168.31.167 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::volumioGetVisibleSources Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::volumioGetState Apr 12 12:45:30 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 12 12:45:30 volumio volumio[1409]: info: Received Get System Info Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 12 12:45:30 volumio volumio[1409]: info: Discovery: Getting this device information Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::volumioGetState Apr 12 12:45:30 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::volumioGetState Apr 12 12:45:30 volumio volumio[1409]: info: CorePlayQueue::getTrack 0 Apr 12 12:45:30 volumio volumio[1409]: info: Listing playlists Apr 12 12:45:30 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: info: Retrieving Cloud Streaming UI Apr 12 12:45:35 volumio volumio[1409]: info: Getting Tidal Cloud Configuration Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: info: Getting Qobuz Cloud Configuration Apr 12 12:45:35 volumio volumio[1409]: info: Asking plugin for UI Config Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: info: Getting Spotify Cloud Configuration Apr 12 12:45:35 volumio volumio[1409]: info: Asking plugin for UI Config Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: info: Saving Spotify Acccount Apr 12 12:45:35 volumio volumio[1409]: info: Got Tidal Cloud Configuration Apr 12 12:45:35 volumio volumio[1409]: info: Got it Apr 12 12:45:35 volumio volumio[1409]: info: Got it Apr 12 12:45:35 volumio volumio[1409]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 12 12:45:35 volumio volumio[1409]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::volumioGetBrowseSources Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 12:45:35 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 12 12:45:39 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 12 12:45:39 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 12 12:45:39 volumio volumio[1409]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 12 12:45:40 volumio volumio[1409]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:45:40 volumio volumio[1409]: Error: getaddrinfo -3007 Apr 12 12:45:40 volumio volumio[1409]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Apr 12 12:45:40 volumio volumio[1409]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Apr 12 12:45:40 volumio volumio[1409]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Apr 12 12:45:40 volumio volumio[1409]: code: -3007, Apr 12 12:45:40 volumio volumio[1409]: errno: -3007, Apr 12 12:45:40 volumio volumio[1409]: syscall: 'getaddrinfo' Apr 12 12:45:40 volumio volumio[1409]: } Apr 12 12:45:40 volumio volumio[1409]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 12 12:45:40 volumio sudo[21104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-12 12:44' Apr 12 12:45:40 volumio sudo[21104]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"