-- Logs begin at Sat 2025-11-01 23:02:27 CET, end at Sat 2025-11-01 23:45:09 CET. -- Nov 01 23:44:03 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:08 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:14 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:19 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:24 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:29 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:34 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: new high-speed USB device number 3 using ehci-pci Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: New USB device found, idVendor=0781, idProduct=5581, bcdDevice= 1.00 Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: Product: SanDisk 3.2Gen1 Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: Manufacturer: USB Nov 01 23:44:37 volumio-keller kernel: usb 2-1.3: SerialNumber: 05010e89e5a04c1e0a806c7a2eea8853f78f6896940a63cd815f74a46414bdc52977000000000000000000001cac21daff94151081558107472c4460 Nov 01 23:44:37 volumio-keller kernel: usb-storage 2-1.3:1.0: USB Mass Storage device detected Nov 01 23:44:37 volumio-keller kernel: scsi host4: usb-storage 2-1.3:1.0 Nov 01 23:44:38 volumio-keller kernel: scsi 4:0:0:0: Direct-Access USB SanDisk 3.2Gen1 1.00 PQ: 0 ANSI: 6 Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: Attached scsi generic sg2 type 0 Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: [sdb] 240328704 512-byte logical blocks: (123 GB/115 GiB) Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: [sdb] Write Protect is off Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: [sdb] Mode Sense: 43 00 00 00 Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Nov 01 23:44:38 volumio-keller kernel: sdb: sdb1 Nov 01 23:44:38 volumio-keller kernel: sd 4:0:0:0: [sdb] Attached SCSI removable disk Nov 01 23:44:38 volumio-keller volumio[958]: info: Mounting Device 123GB Nov 01 23:44:38 volumio-keller sudo[13508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/123GB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 01 23:44:38 volumio-keller sudo[13508]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 23:44:38 volumio-keller sudo[13508]: pam_unix(sudo:session): session closed for user root Nov 01 23:44:38 volumio-keller volumio[958]: $MFTMirr does not match $MFT (record 0). Nov 01 23:44:38 volumio-keller volumio[958]: Failed to mount '/dev/sdb1': Input/output error Nov 01 23:44:38 volumio-keller volumio[958]: NTFS is either inconsistent, or there is a hardware fault, or it's a Nov 01 23:44:38 volumio-keller volumio[958]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Nov 01 23:44:38 volumio-keller volumio[958]: then reboot into Windows twice. The usage of the /f parameter is very Nov 01 23:44:38 volumio-keller volumio[958]: important! If the device is a SoftRAID/FakeRAID then first activate Nov 01 23:44:38 volumio-keller volumio[958]: it and mount a different device under the /dev/mapper/ directory, (e.g. Nov 01 23:44:38 volumio-keller volumio[958]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Nov 01 23:44:38 volumio-keller volumio[958]: for more details. Nov 01 23:44:38 volumio-keller volumio[958]: error: Failed to mount 123GB: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/123GB" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 01 23:44:38 volumio-keller volumio[958]: $MFTMirr does not match $MFT (record 0). Nov 01 23:44:38 volumio-keller volumio[958]: Failed to mount '/dev/sdb1': Input/output error Nov 01 23:44:38 volumio-keller volumio[958]: NTFS is either inconsistent, or there is a hardware fault, or it's a Nov 01 23:44:38 volumio-keller volumio[958]: SoftRAID/FakeRAID hardware. In the first case run chkdsk /f on Windows Nov 01 23:44:38 volumio-keller volumio[958]: then reboot into Windows twice. The usage of the /f parameter is very Nov 01 23:44:38 volumio-keller volumio[958]: important! If the device is a SoftRAID/FakeRAID then first activate Nov 01 23:44:38 volumio-keller volumio[958]: it and mount a different device under the /dev/mapper/ directory, (e.g. Nov 01 23:44:38 volumio-keller volumio[958]: /dev/mapper/nvidia_eahaabcc1). Please see the 'dmraid' documentation Nov 01 23:44:38 volumio-keller volumio[958]: for more details. Nov 01 23:44:39 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:44 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:49 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:49 volumio-keller sudo[13553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 01 23:44:49 volumio-keller sudo[13553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 23:44:49 volumio-keller sudo[13553]: pam_unix(sudo:session): session closed for user root Nov 01 23:44:49 volumio-keller sudo[13558]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 01 23:44:49 volumio-keller sudo[13558]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 23:44:49 volumio-keller sudo[13558]: pam_unix(sudo:session): session closed for user root Nov 01 23:44:50 volumio-keller volumio[958]: verbose: New Socket.io Connection to 192.168.2.164 from 192.168.2.51 UA: Mozilla/5.0 (X11; Linux x86_64; rv:144.0) Gecko/20100101 Firefox/144.0 Engine version: 3 Transport: polling Total Clients: 8 Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 01 23:44:50 volumio-keller volumio[958]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Nov 01 23:44:50 volumio-keller volumio[958]: info: Received Get System Info Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:44:50 volumio-keller volumio[958]: info: Discovery: Getting this device information Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:50 volumio-keller volumio[958]: info: Listing playlists Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:44:50 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:44:51 volumio-keller volumio[958]: info: Received Get System Info Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:44:51 volumio-keller volumio[958]: info: Discovery: Getting this device information Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:51 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:44:53 volumio-keller volumio[958]: info: Executing endpoint metavolumio Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:44:53 volumio-keller volumio[958]: info: Executing endpoint metavolumio Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: Retrieving Cloud Streaming UI Nov 01 23:44:53 volumio-keller volumio[958]: info: Getting Tidal Cloud Configuration Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: Getting Qobuz Cloud Configuration Nov 01 23:44:53 volumio-keller volumio[958]: info: Asking plugin for UI Config Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: Getting Spotify Cloud Configuration Nov 01 23:44:53 volumio-keller volumio[958]: info: Asking plugin for UI Config Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: Saving Spotify Acccount Nov 01 23:44:53 volumio-keller volumio[958]: info: Got it Nov 01 23:44:53 volumio-keller volumio[958]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 23:44:53 volumio-keller volumio[958]: info: Got Tidal Cloud Configuration Nov 01 23:44:53 volumio-keller volumio[958]: info: Got it Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 01 23:44:53 volumio-keller volumio[958]: info: Received Get System Info Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:44:53 volumio-keller volumio[958]: info: Discovery: Getting this device information Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:53 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:44:55 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:44:57 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 23:44:58 volumio-keller nmbd[2690]: [2025/11/01 23:44:58.695012, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Nov 01 23:44:58 volumio-keller nmbd[2690]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.164 for name WORKGROUP<1d>. Nov 01 23:44:58 volumio-keller nmbd[2690]: This response was from IP 192.168.2.51, reporting an IP address of 192.168.2.51. Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand update Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand update took 2 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 2 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 1 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 178 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 177 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 4 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 3 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 3 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 5 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654606,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ------------------------------ 278ms Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: Nov 01 23:44:59 volumio-keller volumio[958]: ---------------------------- MPD announces state update: update Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::getState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand status Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 69 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 47 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 26 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 12 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 13 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 12 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand status took 12 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: info: Command Router : Notfying DB Updatefalse Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::Close All Modals sent Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseState Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654606,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654857,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654857,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ------------------------------ 461ms Nov 01 23:44:59 volumio-keller volumio[958]: info: ------------------------------ 286ms Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 83 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: info: sendMpdCommand playlistinfo took 41 milliseconds Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: verbose: ControllerMpd::parseTrackInfo Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654983,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ControllerMpd::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::servicePushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CorePlayQueue::getTrack 0 Nov 01 23:44:59 volumio-keller volumio[958]: verbose: STATE SERVICE {"status":"play","position":0,"seek":654983,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"87 Kbps","isStreaming":false,"title":"François de Ribaupierre - Valse sur Mer","artist":"Swiss Jazz","album":null,"uri":"http://stream.srg-ssr.ch/m/rsj/aacp_96","trackType":""} Nov 01 23:44:59 volumio-keller volumio[958]: verbose: CURRENT POSITION 0 Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState stateService play Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::syncState currentStatus play Nov 01 23:44:59 volumio-keller volumio[958]: info: Received an update from plugin. extracting info from payload Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreStateMachine::pushState Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioPushState Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output update for this device Nov 01 23:44:59 volumio-keller volumio[958]: info: MRS: Pushing multiroomSync output Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:44:59 volumio-keller volumio[958]: info: ------------------------------ 219ms Nov 01 23:44:59 volumio-keller volumio[958]: info: ------------------------------ 219ms Nov 01 23:44:59 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Nov 01 23:45:00 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:45:01 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 23:45:03 volumio-keller nmbd[2690]: [2025/11/01 23:45:03.701169, 0] ../source3/libsmb/nmblib.c:917(send_udp) Nov 01 23:45:03 volumio-keller nmbd[2690]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Nov 01 23:45:05 volumio-keller kernel: perf: interrupt took too long (3131 > 3127), lowering kernel.perf_event_max_sample_rate to 63750 Nov 01 23:45:05 volumio-keller kernel: intel ips 0000:00:1f.6: failed to disable graphics turbo Nov 01 23:45:05 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 23:45:08 volumio-keller volumio[958]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Nov 01 23:45:08 volumio-keller volumio[958]: info: Received Get System Version Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 23:45:08 volumio-keller volumio[958]: info: Received Get System Info Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 01 23:45:08 volumio-keller volumio[958]: info: Discovery: Getting this device information Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::volumioGetState Nov 01 23:45:08 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 01 23:45:09 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 01 23:45:09 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 01 23:45:09 volumio-keller volumio[958]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 23:45:09 volumio-keller volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:45:09 volumio-keller volumio[958]: Error: dns service error: unknown Nov 01 23:45:09 volumio-keller volumio[958]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Nov 01 23:45:09 volumio-keller volumio[958]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Nov 01 23:45:09 volumio-keller volumio[958]: errorCode: -65537 Nov 01 23:45:09 volumio-keller volumio[958]: } Nov 01 23:45:09 volumio-keller volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 23:45:09 volumio-keller sudo[13662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 23:44 Nov 01 23:45:09 volumio-keller sudo[13662]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"