-- Logs begin at Tue 2024-09-17 17:06:54 CEST, end at Thu 2024-10-24 18:25:42 CEST. -- Oct 24 18:24:01 volumio kernel: usb 1-1.2: USB disconnect, device number 6 Oct 24 18:24:01 volumio volumio[961]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-General_UDisk_2406271149461371546811-0:0-part1 /dev/disk/by-uuid/480A-5894 /dev/disk/by-partuuid/056d432f-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"480A-5894","ID_FS_UUID_ENC":"480A-5894","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"UDisk","ID_MODEL_ENC":"UDisk\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"128","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"7864032","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"056d432f-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"056d432f","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"5.00","ID_SERIAL":"General_UDisk_2406271149461371546811-0:0","ID_SERIAL_SHORT":"2406271149461371546811","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"General","ID_VENDOR_ENC":"General\\x20","ID_VENDOR_ID":"abcd","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"1960","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"371931603"} Oct 24 18:24:01 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Oct 24 18:24:01 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 18:24:01 volumio systemd[1]: media-480A\x2d5894.mount: Succeeded. Oct 24 18:24:02 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root Oct 24 18:24:02 volumio volumio[961]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/056d432f-02 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-id/usb-General_UDisk_2406271149461371546811-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"27","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"UDisk","ID_MODEL_ENC":"UDisk\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"7864227","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"63","ID_PART_ENTRY_TYPE":"0xea","ID_PART_ENTRY_UUID":"056d432f-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"056d432f","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"5.00","ID_SERIAL":"General_UDisk_2406271149461371546811-0:0","ID_SERIAL_SHORT":"2406271149461371546811","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"General","ID_VENDOR_ENC":"General\\x20","ID_VENDOR_ID":"abcd","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"1961","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"371865093"} Oct 24 18:24:02 volumio volumio[961]: error: Cannot associate FS Label, not mounting Oct 24 18:24:06 volumio volumio[961]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/480A-5894 Oct 24 18:24:07 volumio volumio[961]: info: Scanning removed location : "USB/480A-5894" Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: MPD Database updated - AlbumList cache refreshed Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: database Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 15 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 14 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 12 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 12 milliseconds Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 165ms Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 163ms Oct 24 18:24:07 volumio volumio[961]: info: MPD Database updated - AlbumList cache refreshed Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: database Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: Oct 24 18:24:07 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 170 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 165 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 164 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 9 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 8 milliseconds Oct 24 18:24:07 volumio volumio[961]: info: sendMpdCommand status took 7 milliseconds Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:07 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: No code Oct 24 18:24:07 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:07 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:07 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 343ms Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 344ms Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 183ms Oct 24 18:24:07 volumio volumio[961]: info: ------------------------------ 187ms Oct 24 18:24:13 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Oct 24 18:24:13 volumio kernel: usb 1-1.2: New USB device found, idVendor=abcd, idProduct=1234, bcdDevice= 1.00 Oct 24 18:24:13 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Oct 24 18:24:13 volumio kernel: usb 1-1.2: Product: UDisk Oct 24 18:24:13 volumio kernel: usb 1-1.2: Manufacturer: General Oct 24 18:24:13 volumio kernel: usb 1-1.2: SerialNumber: 2406271149461371546811 Oct 24 18:24:13 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Oct 24 18:24:13 volumio kernel: scsi host0: usb-storage 1-1.2:1.0 Oct 24 18:24:14 volumio kernel: scsi 0:0:0:0: Direct-Access General UDisk 5.00 PQ: 0 ANSI: 2 Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] 7864320 512-byte logical blocks: (4.03 GB/3.75 GiB) Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 0b 00 00 08 Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] No Caching mode page found Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] Assuming drive cache: write through Oct 24 18:24:14 volumio kernel: sda: sda1 sda2 Oct 24 18:24:14 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Oct 24 18:24:14 volumio volumio[961]: error: Cannot associate FS Label, not mounting Oct 24 18:24:14 volumio volumio[961]: info: Mounting Device 480A-5894 Oct 24 18:24:14 volumio sudo[2464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/480A-5894 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 24 18:24:14 volumio sudo[2464]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 24 18:24:14 volumio kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Oct 24 18:24:14 volumio kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Oct 24 18:24:14 volumio sudo[2464]: pam_unix(sudo:session): session closed for user root Oct 24 18:24:14 volumio volumio[961]: info: Scanning new location : "USB/480A-5894" Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 8 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 7 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 6 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 4 milliseconds Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: info: Command Router : Notfying DB Updatetrue Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: info: Command Router : Notfying DB Updatetrue Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:14 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: No code Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:14 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: No code Oct 24 18:24:14 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:14 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:14 volumio volumio[961]: info: ------------------------------ 166ms Oct 24 18:24:14 volumio volumio[961]: info: ------------------------------ 163ms Oct 24 18:24:14 volumio volumio[961]: info: MPD Database updated - AlbumList cache refreshed Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: database Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: MPD Database updated - AlbumList cache refreshed Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: database Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: Oct 24 18:24:14 volumio volumio[961]: ---------------------------- MPD announces state update: update Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 23 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 22 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 20 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 18 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 16 milliseconds Oct 24 18:24:14 volumio volumio[961]: info: sendMpdCommand status took 16 milliseconds Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: info: Command Router : Notfying DB Updatefalse Oct 24 18:24:14 volumio volumio[961]: info: CoreCommandRouter::Close All Modals sent Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:14 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:24:15 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:15 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: No code Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:15 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: No code Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:15 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: No code Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 24 18:24:15 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState stateService stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: No code Oct 24 18:24:15 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:24:15 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:24:15 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:24:15 volumio volumio[961]: info: ------------------------------ 209ms Oct 24 18:24:15 volumio volumio[961]: info: ------------------------------ 210ms Oct 24 18:24:15 volumio volumio[961]: info: ------------------------------ 204ms Oct 24 18:24:15 volumio volumio[961]: info: ------------------------------ 206ms Oct 24 18:24:37 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:37 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:40 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:41 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:44 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:45 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:50 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:50 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:54 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:54 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:55 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:56 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:59 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:24:59 volumio volumio[961]: info: Preload queue cleared Oct 24 18:24:59 volumio volumio[961]: info: Preloading song: upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@5:audio5:part11:12:21 Oct 24 18:24:59 volumio volumio[961]: info: Exploding uri upnp/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@5:audio5:part11:12:21 in service upnp_browser Oct 24 18:24:59 volumio volumio[961]: info: No valid Plugin REST Endpoint Oct 24 18:24:59 volumio volumio[961]: info: No valid Plugin REST Endpoint Oct 24 18:25:01 volumio volumio[961]: info: Preload queue cleared Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::ClearQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::stop Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::clearPlayQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::saveQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::addQueueItems Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::addQueueItems Oct 24 18:25:01 volumio volumio[961]: info: Preload queue cleared Oct 24 18:25:01 volumio volumio[961]: info: Adding Item to queue: upnp/folder/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@4:cont2:549:FRITZ!Box3:AVM0: Oct 24 18:25:01 volumio volumio[961]: info: Exploding uri upnp/folder/http://192.168.178.1:49000/MediaServer/ContentDirectory/Control@4:cont2:549:FRITZ!Box3:AVM0: in service upnp_browser Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::saveQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::updateTrackBlock Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrackBlock Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPlay Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::play index 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::stop Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::play index undefined Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::startPlaybackTimer Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: info: [1729787101493] ControllerUPNPBrowser::clearAddPlayTrack Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand stop Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand stop took 3 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand clear Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand clear took 3 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3" Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: error: updateQueue error: null Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 10ms Oct 24 18:25:01 volumio volumio[961]: error: Upnp client error: Error: This socket has been ended by the other party Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3" Oct 24 18:25:01 volumio volumio[961]: error: updateQueue error: null Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 59ms Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand add "http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3" took 4 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand play Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 8ms Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand play took 4 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 4ms Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 7 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 28 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FRITZ-Song.mp3","artist":null,"album":null,"uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus stop Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 45ms Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 20 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 5 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Oct 24 18:25:01 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 86ms Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces system playlist update Oct 24 18:25:01 volumio volumio[961]: info: Ignoring MPD Status Update Oct 24 18:25:01 volumio volumio[961]: info: Oct 24 18:25:01 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 47ms Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 46 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 43 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 9ms Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand status took 7 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Oct 24 18:25:01 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Oct 24 18:25:01 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 147ms Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 116ms Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 89 milliseconds Oct 24 18:25:01 volumio volumio[961]: info: sendMpdCommand playlistinfo took 86 milliseconds Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Oct 24 18:25:01 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:01 volumio volumio[961]: verbose: STATE SERVICE {"status":"play","position":0,"seek":976,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:01 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState stateService play Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus play Oct 24 18:25:01 volumio volumio[961]: info: Received an update from plugin. extracting info from payload Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 202ms Oct 24 18:25:01 volumio volumio[961]: info: ------------------------------ 162ms Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:01 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:01 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::volumioPause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::pause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::stPlaybackTimer Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::servicePause Oct 24 18:25:31 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::servicePause Oct 24 18:25:31 volumio volumio[961]: info: [1729787131494] ControllerUPNPBrowser::pause Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand pause Oct 24 18:25:31 volumio volumio[961]: info: sendMpdCommand pause took 2 milliseconds Oct 24 18:25:31 volumio volumio[961]: info: Oct 24 18:25:31 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:31 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:31 volumio volumio[961]: info: Oct 24 18:25:31 volumio volumio[961]: ---------------------------- MPD announces state update: player Oct 24 18:25:31 volumio volumio[961]: info: ControllerMpd::getState Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand status Oct 24 18:25:31 volumio volumio[961]: info: sendMpdCommand status took 6 milliseconds Oct 24 18:25:31 volumio volumio[961]: info: sendMpdCommand status took 3 milliseconds Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::parseState Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 24 18:25:31 volumio volumio[961]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 24 18:25:31 volumio volumio[961]: info: sendMpdCommand playlistinfo took 3 milliseconds Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:31 volumio volumio[961]: verbose: ControllerMpd::parseTrackInfo Oct 24 18:25:31 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:31 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:31 volumio volumio[961]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30854,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:31 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::syncState stateService pause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus pause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::stPlaybackTimer Oct 24 18:25:31 volumio volumio[961]: info: ControllerMpd::pushState Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::servicePushState Oct 24 18:25:31 volumio volumio[961]: info: CorePlayQueue::getTrack 0 Oct 24 18:25:31 volumio volumio[961]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":30854,"duration":69,"samplerate":"32 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"80 Kbps","isStreaming":false,"title":"FRITZ!","artist":"AVM","album":"FRITZ!Box","uri":"http://192.168.178.1:49200/AUDIO/DLNA-1-0/Musik/FRITZ-Song.mp3","trackType":"mp3"} Oct 24 18:25:31 volumio volumio[961]: verbose: CURRENT POSITION 0 Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::syncState stateService pause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::syncState currentStatus pause Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::pushState Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::volumioPushState Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::stPlaybackTimer Oct 24 18:25:31 volumio volumio[961]: info: ------------------------------ 47ms Oct 24 18:25:31 volumio volumio[961]: info: ------------------------------ 44ms Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:31 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:31 volumio volumio[961]: info: CoreCommandRouter::volumioGetQueue Oct 24 18:25:31 volumio volumio[961]: info: CoreStateMachine::getQueue Oct 24 18:25:31 volumio volumio[961]: info: CorePlayQueue::getQueue Oct 24 18:25:41 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 24 18:25:41 volumio volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 18:25:41 volumio volumio[961]: TypeError: Cannot read property 'length' of undefined Oct 24 18:25:41 volumio volumio[961]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 24 18:25:41 volumio volumio[961]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 24 18:25:41 volumio volumio[961]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 24 18:25:41 volumio volumio[961]: at Parser.emit (events.js:315:20) Oct 24 18:25:41 volumio volumio[961]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 24 18:25:41 volumio volumio[961]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 24 18:25:41 volumio volumio[961]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 24 18:25:41 volumio volumio[961]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 24 18:25:41 volumio volumio[961]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 24 18:25:41 volumio volumio[961]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 24 18:25:41 volumio volumio[961]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 24 18:25:41 volumio volumio[961]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 24 18:25:41 volumio volumio[961]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 24 18:25:41 volumio volumio[961]: at IncomingMessage.emit (events.js:327:22) Oct 24 18:25:41 volumio volumio[961]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 24 18:25:41 volumio volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 24 18:25:41 volumio volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 24 18:25:42 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-24 18:24 Oct 24 18:25:42 volumio sudo[2601]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"