Dec 11 22:56:05 volumio kernel: usb 1-1.2: USB disconnect, device number 37 Dec 11 22:56:05 volumio volumio[1309]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","ACTION":"remove","CURRENT_TAGS":":systemd:","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-VendorCo_ProductCode_KT2020000001813-0:0-part1 /dev/disk/by-label/ESD-USB /dev/disk/by-partuuid/80a02c42-01 /dev/disk/by-uuid/6C17-1120","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host2/target2:0:0/2:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"40","ID_BUS":"usb","ID_FS_LABEL":"ESD-USB","ID_FS_LABEL_ENC":"ESD-USB","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6C17-1120","ID_FS_UUID_ENC":"6C17-1120","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"ProductCode","ID_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5678","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"61437952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"80a02c42-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"80a02c42","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":"2.00","ID_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_SERIAL_SHORT":"KT2020000001813","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"ProductCode","ID_USB_MODEL_ENC":"ProductCode\\x20\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"5678","ID_USB_REVISION":"2.00","ID_USB_SERIAL":"VendorCo_ProductCode_KT2020000001813-0:0","ID_USB_SERIAL_SHORT":"KT2020000001813","ID_USB_TYPE":"disk","ID_USB_VENDOR":"VendorCo","ID_USB_VENDOR_ENC":"VendorCo","ID_USB_VENDOR_ID":"ffff","ID_VENDOR":"VendorCo","ID_VENDOR_ENC":"VendorCo","ID_VENDOR_ID":"ffff","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"2767","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"36985442828"} Dec 11 22:56:06 volumio sudo[2190]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Dec 11 22:56:06 volumio sudo[2190]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:56:06 volumio sudo[2190]: pam_unix(sudo:session): session closed for user root Dec 11 22:56:06 volumio kernel: usb 1-1.2: new high-speed USB device number 38 using dwc_otg Dec 11 22:56:06 volumio kernel: usb 1-1.2: New USB device found, idVendor=ffff, idProduct=5678, bcdDevice= 2.00 Dec 11 22:56:06 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 11 22:56:06 volumio kernel: usb 1-1.2: Product: Disk 2.0 Dec 11 22:56:06 volumio kernel: usb 1-1.2: Manufacturer: USB Dec 11 22:56:06 volumio kernel: usb 1-1.2: SerialNumber: KT2020000001813 Dec 11 22:56:06 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 11 22:56:06 volumio kernel: scsi host2: usb-storage 1-1.2:1.0 Dec 11 22:56:07 volumio kernel: scsi 2:0:0:0: Direct-Access VendorCo ProductCode 2.00 PQ: 0 ANSI: 4 Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: Attached scsi generic sg0 type 0 Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] 61440000 512-byte logical blocks: (31.5 GB/29.3 GiB) Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] Write Protect is off Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] Mode Sense: 03 00 00 00 Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] No Caching mode page found Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] Assuming drive cache: write through Dec 11 22:56:07 volumio kernel: sdc: sdc1 Dec 11 22:56:07 volumio kernel: sd 2:0:0:0: [sdc] Attached SCSI removable disk Dec 11 22:56:07 volumio volumio[1309]: info: Mounting Device ESD-USB Dec 11 22:56:07 volumio sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/ESD-USB -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 11 22:56:07 volumio sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:56:07 volumio kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 11 22:56:07 volumio kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Dec 11 22:56:07 volumio sudo[2203]: pam_unix(sudo:session): session closed for user root Dec 11 22:56:07 volumio volumio[1309]: info: Scanning new location : "USB/ESD-USB" Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: info: Dec 11 22:56:07 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:07 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: info: Dec 11 22:56:07 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:07 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: info: Dec 11 22:56:07 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:07 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 10 milliseconds Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 10 milliseconds Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 10 milliseconds Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 9 milliseconds Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 7 milliseconds Dec 11 22:56:07 volumio volumio[1309]: info: sendMpdCommand status took 6 milliseconds Dec 11 22:56:07 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: info: Dec 11 22:56:08 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 37 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 198ms Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 196ms Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 194ms Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: info: Dec 11 22:56:08 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: info: Dec 11 22:56:08 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 42 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 41 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 17 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 16 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 16 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand status took 15 milliseconds Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 11 22:56:08 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 9 milliseconds Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:08 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:08 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:08 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:08 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 154ms Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 129ms Dec 11 22:56:08 volumio volumio[1309]: info: ------------------------------ 128ms Dec 11 22:56:10 volumio volumio[1309]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/ESD-USB Dec 11 22:56:11 volumio volumio[1309]: info: Scanning removed location : "USB/ESD-USB" Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 11 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 9 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 7 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 6 milliseconds Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: Dec 11 22:56:11 volumio volumio[1309]: ---------------------------- MPD announces state update: update Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 104 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 102 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::getState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand status Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 43 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 17 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 11 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 10 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 8 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 6 milliseconds Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatetrue Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 218ms Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 215ms Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 125 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand status took 124 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 93 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 65 milliseconds Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: info: Command Router : Notfying DB Updatefalse Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::Close All Modals sent Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseState Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 276ms Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 182ms Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 179ms Dec 11 22:56:11 volumio volumio[1309]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 11 22:56:11 volumio volumio[1309]: verbose: ControllerMpd::parseTrackInfo Dec 11 22:56:11 volumio volumio[1309]: info: ControllerMpd::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::servicePushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":24827,"duration":161,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"160 Kbps","isStreaming":false,"title":"Sleigh ride (Leroy Anderson)","artist":null,"album":null,"uri":"USB/ESD-USB/01_Sleigh_ride__Leroy_Anderson_.mp3","trackType":"mp3"} Dec 11 22:56:11 volumio volumio[1309]: verbose: CURRENT POSITION 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState stateService pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::syncState currentStatus pause Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::pushState Dec 11 22:56:11 volumio volumio[1309]: info: CorePlayQueue::getTrack 1 Dec 11 22:56:11 volumio volumio[1309]: info: CoreCommandRouter::volumioPushState Dec 11 22:56:11 volumio volumio[1309]: info: CoreStateMachine::stPlaybackTimer Dec 11 22:56:11 volumio volumio[1309]: info: ------------------------------ 185ms Dec 11 22:57:50 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 22:57:50 volumio volumio[1309]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 11 22:57:53 volumio sudo[2414]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 11 22:57:53 volumio sudo[2414]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:57:53 volumio sudo[2414]: pam_unix(sudo:session): session closed for user root Dec 11 22:57:53 volumio sudo[2416]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 11 22:57:53 volumio sudo[2416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:57:53 volumio volumio[1309]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 11 22:57:53 volumio sudo[2421]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 11 22:57:53 volumio sudo[2421]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:57:53 volumio volumio[1309]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:57:53 volumio sudo[2418]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 11 22:57:53 volumio sudo[2418]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 11 22:57:53 volumio volumio[1309]: TypeError: Cannot read properties of undefined (reading 'split') Dec 11 22:57:53 volumio volumio[1309]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 11 22:57:53 volumio volumio[1309]: at ChildProcess.exithandler (node:child_process:413:7) Dec 11 22:57:53 volumio volumio[1309]: at ChildProcess.emit (node:events:514:28) Dec 11 22:57:53 volumio volumio[1309]: at maybeClose (node:internal/child_process:1105:16) Dec 11 22:57:53 volumio volumio[1309]: at Socket. (node:internal/child_process:457:11) Dec 11 22:57:53 volumio volumio[1309]: at Socket.emit (node:events:514:28) Dec 11 22:57:53 volumio volumio[1309]: at Pipe. (node:net:337:12) Dec 11 22:57:53 volumio volumio[1309]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 22:57:54 volumio systemd[1]: Reloading. Dec 11 22:57:55 volumio sudo[2456]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 22:56' Dec 11 22:57:55 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"