-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-10-31 15:17:04 CET. -- Oct 31 15:16:01 volumio kernel: usb 1-1.4: USB disconnect, device number 4 Oct 31 15:16:01 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Oct 31 15:16:01 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x01 driverbyte=DRIVER_OK Oct 31 15:16:01 volumio sudo[3517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda2 Oct 31 15:16:01 volumio volumio[932]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partlabel/X30-SWAP /dev/disk/by-path/platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-Intenso_SSD_Sata_III_0000AB123573-0:0-part1 /dev/disk/by-partuuid/5664cff7-8772-4002-9428-ec892822b141","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_TYPE":"swap","ID_FS_USAGE":"other","ID_FS_VERSION":"1","ID_INSTANCE":"0:0","ID_MODEL":"SSD_Sata_III","ID_MODEL_ENC":"SSD\\x20Sata\\x20III\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"X30-SWAP","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"999967","ID_PART_ENTRY_TYPE":"0657fd6d-a4ab-43c4-84e5-0933c84b4f4f","ID_PART_ENTRY_UUID":"5664cff7-8772-4002-9428-ec892822b141","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"d1327bcb-456d-485c-a68c-e69fc1bab641","ID_PATH":"platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"8101","ID_SERIAL":"Intenso_SSD_Sata_III_0000AB123573-0:0","ID_SERIAL_SHORT":"0000AB123573","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Intenso","ID_VENDOR_ENC":"Intenso\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","PARTNAME":"X30-SWAP","SEQNUM":"2054","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"44412157"} Oct 31 15:16:01 volumio volumio[932]: error: Cannot associate FS Label, not mounting Oct 31 15:16:01 volumio sudo[3517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 15:16:01 volumio volumio[932]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-uuid/6094AE4694AE1E92 /dev/disk/by-id/usb-Intenso_SSD_Sata_III_0000AB123573-0:0-part2 /dev/disk/by-partlabel/X30-HDD /dev/disk/by-partuuid/230d8eb5-7f54-4ffe-9c66-012023823558 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6094AE4694AE1E92","ID_FS_UUID_ENC":"6094AE4694AE1E92","ID_INSTANCE":"0:0","ID_MODEL":"SSD_Sata_III","ID_MODEL_ENC":"SSD\\x20Sata\\x20III\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0578","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NAME":"X30-HDD","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"1000001","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"499116239","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"230d8eb5-7f54-4ffe-9c66-012023823558","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"d1327bcb-456d-485c-a68c-e69fc1bab641","ID_PATH":"platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"8101","ID_SERIAL":"Intenso_SSD_Sata_III_0000AB123573-0:0","ID_SERIAL_SHORT":"0000AB123573","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Intenso","ID_VENDOR_ENC":"Intenso\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"2","PARTN":"2","PARTNAME":"X30-HDD","SEQNUM":"2055","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"44428133"} Oct 31 15:16:01 volumio volumio[932]: umount: /media/6094AE4694AE1E92: target is busy. Oct 31 15:16:01 volumio volumio[932]: error: Failed to umount 6094AE4694AE1E92: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda2" Oct 31 15:16:01 volumio volumio[932]: umount: /media/6094AE4694AE1E92: target is busy. Oct 31 15:16:01 volumio ntfs-3g[1057]: Unmounting /dev/sda2 () Oct 31 15:16:01 volumio ntfs-3g[1057]: Failed to sync device /dev/sda2: Input/output error Oct 31 15:16:01 volumio ntfs-3g[1057]: Failed to close volume /dev/sda2: Input/output error Oct 31 15:16:01 volumio sudo[3517]: pam_unix(sudo:session): session closed for user root Oct 31 15:16:06 volumio volumio[932]: info: Scanning removed location : "USB/6094AE4694AE1E92" Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:06 volumio volumio[932]: info: Oct 31 15:16:06 volumio volumio[932]: ---------------------------- MPD announces state update: update Oct 31 15:16:06 volumio volumio[932]: info: ControllerMpd::getState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:06 volumio volumio[932]: info: MPD Database updated - AlbumList cache refreshed Oct 31 15:16:06 volumio volumio[932]: info: Oct 31 15:16:06 volumio volumio[932]: ---------------------------- MPD announces state update: database Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:06 volumio volumio[932]: info: Oct 31 15:16:06 volumio volumio[932]: ---------------------------- MPD announces state update: update Oct 31 15:16:06 volumio volumio[932]: info: ControllerMpd::getState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:06 volumio volumio[932]: info: ControllerMpd::getState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand status took 5 milliseconds Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand status took 4 milliseconds Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:06 volumio volumio[932]: info: Command Router : Notfying DB Updatefalse Oct 31 15:16:06 volumio volumio[932]: info: CoreCommandRouter::Close All Modals sent Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand status took 51 milliseconds Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand status took 51 milliseconds Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand status took 50 milliseconds Oct 31 15:16:06 volumio volumio[932]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:06 volumio volumio[932]: info: Command Router : Notfying DB Updatefalse Oct 31 15:16:06 volumio volumio[932]: info: CoreCommandRouter::Close All Modals sent Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 15:16:06 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo Oct 31 15:16:07 volumio volumio[932]: info: ControllerMpd::pushState Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::servicePushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":40176,"duration":440,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"I'll Play The Blues For You (Pts 1 & 2)","artist":"Albert King","album":"I'll Play The Blues For You","uri":"USB/6094AE4694AE1E92/Albert King/I'll Play The Blues For You/01 I'll Play The Blues For You (Pts 1 & 2).flac","trackType":"flac"} Oct 31 15:16:07 volumio volumio[932]: verbose: CURRENT POSITION 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState stateService pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer Oct 31 15:16:07 volumio volumio[932]: info: ------------------------------ 101ms Oct 31 15:16:07 volumio volumio[932]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 31 15:16:07 volumio volumio[932]: info: sendMpdCommand playlistinfo took 25 milliseconds Oct 31 15:16:07 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo Oct 31 15:16:07 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo Oct 31 15:16:07 volumio volumio[932]: info: ControllerMpd::pushState Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::servicePushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":40176,"duration":440,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"I'll Play The Blues For You (Pts 1 & 2)","artist":"Albert King","album":"I'll Play The Blues For You","uri":"USB/6094AE4694AE1E92/Albert King/I'll Play The Blues For You/01 I'll Play The Blues For You (Pts 1 & 2).flac","trackType":"flac"} Oct 31 15:16:07 volumio volumio[932]: verbose: CURRENT POSITION 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState stateService pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer Oct 31 15:16:07 volumio volumio[932]: info: ControllerMpd::pushState Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::servicePushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":40176,"duration":440,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1414 Kbps","isStreaming":false,"title":"I'll Play The Blues For You (Pts 1 & 2)","artist":"Albert King","album":"I'll Play The Blues For You","uri":"USB/6094AE4694AE1E92/Albert King/I'll Play The Blues For You/01 I'll Play The Blues For You (Pts 1 & 2).flac","trackType":"flac"} Oct 31 15:16:07 volumio volumio[932]: verbose: CURRENT POSITION 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState stateService pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus pause Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:07 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:07 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:07 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer Oct 31 15:16:07 volumio volumio[932]: info: ------------------------------ 123ms Oct 31 15:16:07 volumio volumio[932]: info: ------------------------------ 123ms Oct 31 15:16:20 volumio systemd[1]: Starting Cleanup of Temporary Directories... Oct 31 15:16:20 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Oct 31 15:16:20 volumio systemd[1]: Started Cleanup of Temporary Directories. Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: info: Retrieving Cloud Streaming UI Oct 31 15:16:32 volumio volumio[932]: info: Getting Tidal Cloud Configuration Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: info: Getting Qobuz Cloud Configuration Oct 31 15:16:32 volumio volumio[932]: info: Asking plugin for UI Config Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: info: Getting Spotify Cloud Configuration Oct 31 15:16:32 volumio volumio[932]: info: Asking plugin for UI Config Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: info: Saving Spotify Acccount Oct 31 15:16:32 volumio volumio[932]: info: Got Tidal Cloud Configuration Oct 31 15:16:32 volumio volumio[932]: info: Got it Oct 31 15:16:32 volumio volumio[932]: info: Got it Oct 31 15:16:32 volumio volumio[932]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 31 15:16:32 volumio volumio[932]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::volumioGetBrowseSources Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 31 15:16:32 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 31 15:16:37 volumio volumio[932]: info: CoreCommandRouter::volumioGetState Oct 31 15:16:37 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:48 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 15:16:48 volumio volumio[932]: info: Preload queue cleared Oct 31 15:16:50 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 15:16:52 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 15:16:54 volumio volumio[932]: info: Preload queue cleared Oct 31 15:16:54 volumio volumio[932]: info: Preload queue cleared Oct 31 15:16:56 volumio volumio[932]: info: Preload queue cleared Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::ClearQueue Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::stop Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrackBlock Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::stPlaybackTimer Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::serviceStop Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::serviceStop Oct 31 15:16:56 volumio volumio[932]: info: ControllerMpd::stop Oct 31 15:16:56 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand stop Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::clearPlayQueue Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::saveQueue Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushQueue Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::addQueueItems Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::addQueueItems Oct 31 15:16:56 volumio volumio[932]: info: Preload queue cleared Oct 31 15:16:56 volumio volumio[932]: info: Adding Item to queue: upnp/folder/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@4:cont1:20:0:0: Oct 31 15:16:56 volumio volumio[932]: info: Exploding uri upnp/folder/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@4:cont1:20:0:0: in service upnp_browser Oct 31 15:16:56 volumio volumio[932]: info: Oct 31 15:16:56 volumio volumio[932]: ---------------------------- MPD announces state update: player Oct 31 15:16:56 volumio volumio[932]: info: sendMpdCommand stop took 34 milliseconds Oct 31 15:16:56 volumio volumio[932]: info: ControllerMpd::getState Oct 31 15:16:56 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand status Oct 31 15:16:56 volumio volumio[932]: info: sendMpdCommand status took 1 milliseconds Oct 31 15:16:56 volumio volumio[932]: verbose: ControllerMpd::parseState Oct 31 15:16:56 volumio volumio[932]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 15:16:56 volumio volumio[932]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 31 15:16:56 volumio volumio[932]: verbose: ControllerMpd::parseTrackInfo Oct 31 15:16:56 volumio volumio[932]: info: ControllerMpd::pushState Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::servicePushState Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I'll Play The Blues For You (Pts 1 & 2)","artist":"Albert King","album":"I'll Play The Blues For You","uri":"USB/6094AE4694AE1E92/Albert King/I'll Play The Blues For You/01 I'll Play The Blues For You (Pts 1 & 2).flac","trackType":"flac"} Oct 31 15:16:56 volumio volumio[932]: verbose: CURRENT POSITION 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::syncState stateService stop Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::syncState currentStatus stop Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:56 volumio volumio[932]: info: No code Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::pushState Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 1 Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushState Oct 31 15:16:56 volumio volumio[932]: info: ------------------------------ 29ms Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPushQueue Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::saveQueue Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::updateTrackBlock Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrackBlock Oct 31 15:16:56 volumio volumio[932]: info: CoreCommandRouter::volumioPlay Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::play index 0 Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::stop Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::play index undefined Oct 31 15:16:56 volumio volumio[932]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 15:16:56 volumio volumio[932]: info: CorePlayQueue::getTrack 0 Oct 31 15:17:01 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 15:17:01 volumio volumio[932]: info: Preload queue cleared Oct 31 15:17:03 volumio volumio[932]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 31 15:17:03 volumio volumio[932]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 15:17:03 volumio volumio[932]: TypeError: Cannot read property 'length' of undefined Oct 31 15:17:03 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 31 15:17:03 volumio volumio[932]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 31 15:17:03 volumio volumio[932]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 31 15:17:03 volumio volumio[932]: at Parser.emit (events.js:315:20) Oct 31 15:17:03 volumio volumio[932]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 31 15:17:03 volumio volumio[932]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 31 15:17:03 volumio volumio[932]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 31 15:17:03 volumio volumio[932]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 31 15:17:03 volumio volumio[932]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 31 15:17:03 volumio volumio[932]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 31 15:17:03 volumio volumio[932]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 31 15:17:03 volumio volumio[932]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 31 15:17:03 volumio volumio[932]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 31 15:17:03 volumio volumio[932]: at IncomingMessage.emit (events.js:327:22) Oct 31 15:17:03 volumio volumio[932]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 31 15:17:03 volumio volumio[932]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 31 15:17:03 volumio volumio[932]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 15:17:03 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 31 15:17:04 volumio sudo[3674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-31 15:16 Oct 31 15:17:04 volumio sudo[3674]: 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"