Apr 11 19:47:49 lm3886 kernel: usb 2-1: USB disconnect, device number 2 Apr 11 19:47:49 lm3886 volumio[1271]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:1c.3/0000:04:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","CURRENT_TAGS":":systemd:","DEVLINKS":"/dev/disk/by-id/usb-Maxsun_256GB_NM6-22_000000000230-0:0-part1 /dev/disk/by-path/pci-0000:04:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/78132618-01 /dev/disk/by-uuid/5E9446BB94469605","DEVNAME":"/dev/sda1","DEVPATH":"/devices/pci0000:00/0000:00:1c.3/0000:04:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"1","ID_BUS":"usb","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"5E9446BB94469605","ID_FS_UUID_ENC":"5E9446BB94469605","ID_INSTANCE":"0:0","ID_MODEL":"256GB_NM6-22","ID_MODEL_ENC":"256GB\\x20NM6-22\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"500115456","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"78132618-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"78132618","ID_PATH":"pci-0000:04:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_04_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0214","ID_SERIAL":"Maxsun_256GB_NM6-22_000000000230-0:0","ID_SERIAL_SHORT":"000000000230","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INSTANCE":"0:0","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_USB_MODEL":"256GB_NM6-22","ID_USB_MODEL_ENC":"256GB\\x20NM6-22\\x20\\x20\\x20\\x20","ID_USB_MODEL_ID":"0583","ID_USB_REVISION":"0214","ID_USB_SERIAL":"Maxsun_256GB_NM6-22_000000000230-0:0","ID_USB_SERIAL_SHORT":"000000000230","ID_USB_TYPE":"disk","ID_USB_VENDOR":"Maxsun","ID_USB_VENDOR_ENC":"Maxsun\\x20\\x20","ID_USB_VENDOR_ID":"152d","ID_VENDOR":"Maxsun","ID_VENDOR_ENC":"Maxsun\\x20\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3496","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3689051"} Apr 11 19:47:49 lm3886 sudo[2629]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Apr 11 19:47:49 lm3886 sudo[2629]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 19:47:49 lm3886 kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Apr 11 19:47:49 lm3886 kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=DID_NO_CONNECT driverbyte=DRIVER_OK Apr 11 19:47:49 lm3886 ntfs-3g[1665]: Unmounting /dev/sda1 () Apr 11 19:47:49 lm3886 ntfs-3g[1665]: Failed to sync device /dev/sda1: Input/output error Apr 11 19:47:49 lm3886 ntfs-3g[1665]: Failed to close volume /dev/sda1: Input/output error Apr 11 19:47:49 lm3886 systemd[1]: media-5E9446BB94469605.mount: Deactivated successfully. Apr 11 19:47:49 lm3886 sudo[2629]: pam_unix(sudo:session): session closed for user root Apr 11 19:47:52 lm3886 kernel: usb 2-1: new SuperSpeed USB device number 3 using xhci_hcd Apr 11 19:47:52 lm3886 kernel: usb 2-1: New USB device found, idVendor=152d, idProduct=0583, bcdDevice= 2.14 Apr 11 19:47:52 lm3886 kernel: usb 2-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Apr 11 19:47:52 lm3886 kernel: usb 2-1: Product: JMS583 Apr 11 19:47:52 lm3886 kernel: usb 2-1: Manufacturer: JMicron Apr 11 19:47:52 lm3886 kernel: usb 2-1: SerialNumber: 000000000230 Apr 11 19:47:52 lm3886 kernel: usb 2-1: USB controller 0000:04:00.0 does not support streams, which are required by the UAS driver. Apr 11 19:47:52 lm3886 kernel: usb 2-1: Please try an other USB controller if you wish to use UAS. Apr 11 19:47:52 lm3886 kernel: usb-storage 2-1:1.0: USB Mass Storage device detected Apr 11 19:47:52 lm3886 kernel: usb-storage 2-1:1.0: Quirks match for vid 152d pid 0583: 4000000 Apr 11 19:47:52 lm3886 kernel: scsi host0: usb-storage 2-1:1.0 Apr 11 19:47:53 lm3886 volumio[1271]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/5E9446BB94469605 Apr 11 19:47:53 lm3886 kernel: scsi 0:0:0:0: Direct-Access Maxsun 256GB NM6-22 0214 PQ: 0 ANSI: 6 Apr 11 19:47:53 lm3886 kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Apr 11 19:47:54 lm3886 volumio[1271]: info: Scanning removed location : "USB/5E9446BB94469605" Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: info: Apr 11 19:47:54 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:54 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: info: Apr 11 19:47:54 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:54 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: info: Apr 11 19:47:54 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:54 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:54 lm3886 volumio[1271]: info: sendMpdCommand status took 10 milliseconds Apr 11 19:47:54 lm3886 volumio[1271]: info: sendMpdCommand status took 6 milliseconds Apr 11 19:47:54 lm3886 volumio[1271]: info: sendMpdCommand status took 6 milliseconds Apr 11 19:47:54 lm3886 volumio[1271]: info: sendMpdCommand status took 5 milliseconds Apr 11 19:47:54 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:54 lm3886 kernel: sd 0:0:0:0: [sda] 500118192 512-byte logical blocks: (256 GB/238 GiB) Apr 11 19:47:54 lm3886 kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 11 19:47:54 lm3886 kernel: sd 0:0:0:0: [sda] Mode Sense: 53 00 00 08 Apr 11 19:47:54 lm3886 kernel: sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Apr 11 19:47:54 lm3886 kernel: sda: sda1 Apr 11 19:47:54 lm3886 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 976ms Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 973ms Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 973 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 971 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 6 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 5 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 5 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 1189ms Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 220ms Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 233 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 233 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 230 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 259ms Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 267ms Apr 11 19:47:55 lm3886 volumio[1271]: info: Mounting Device 5E9446BB94469605 Apr 11 19:47:55 lm3886 sudo[2676]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/5E9446BB94469605 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 11 19:47:55 lm3886 sudo[2676]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 19:47:55 lm3886 ntfs-3g[2679]: Version 2022.10.3 integrated FUSE 28 Apr 11 19:47:55 lm3886 ntfs-3g[2679]: Mounted /dev/sda1 (Read-Write, label "", NTFS 3.1) Apr 11 19:47:55 lm3886 ntfs-3g[2679]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Apr 11 19:47:55 lm3886 ntfs-3g[2679]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Apr 11 19:47:55 lm3886 ntfs-3g[2679]: Global ownership and permissions enforced, configuration type 7 Apr 11 19:47:55 lm3886 sudo[2676]: pam_unix(sudo:session): session closed for user root Apr 11 19:47:55 lm3886 volumio[1271]: info: Scanning new location : "USB/5E9446BB94469605" Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: Apr 11 19:47:55 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 3 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 2 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 183ms Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 185 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 185 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 186 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: info: sendMpdCommand status took 185 milliseconds Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatetrue Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:47:55 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: 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} Apr 11 19:47:55 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: No code Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:47:55 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:47:55 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 322ms Apr 11 19:47:55 lm3886 volumio[1271]: info: ------------------------------ 323ms Apr 11 19:48:22 lm3886 volumio[1271]: info: MPD Database updated - AlbumList cache refreshed Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: database Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: MPD Database updated - AlbumList cache refreshed Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: database Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: MPD Database updated - AlbumList cache refreshed Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: database Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: Apr 11 19:48:22 lm3886 volumio[1271]: ---------------------------- MPD announces state update: update Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::getState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::sendMpdCommand status Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 197 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 196 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 197 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 416ms Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 416ms Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 496 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 495 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 495 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 566ms Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 566ms Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 625 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 622 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: info: sendMpdCommand status took 621 milliseconds Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: Command Router : Notfying DB Updatefalse Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::Close All Modals sent Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: verbose: ControllerMpd::parseState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ControllerMpd::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::servicePushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: 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} Apr 11 19:48:22 lm3886 volumio[1271]: verbose: CURRENT POSITION 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState stateService stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::syncState currentStatus stop Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: No code Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreStateMachine::pushState Apr 11 19:48:22 lm3886 volumio[1271]: info: CorePlayQueue::getTrack 0 Apr 11 19:48:22 lm3886 volumio[1271]: info: CoreCommandRouter::volumioPushState Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 653ms Apr 11 19:48:22 lm3886 volumio[1271]: info: ------------------------------ 654ms Apr 11 19:48:47 lm3886 volumio[1271]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 11 19:48:47 lm3886 volumio[1271]: info: CURURI: albums:// Apr 11 19:48:47 lm3886 volumio[1271]: info: listAlbums - loading Albums from cache Apr 11 19:48:47 lm3886 volumio[1271]: info: Preload queue cleared Apr 11 19:48:50 lm3886 volumio[1271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:48:50 lm3886 volumio[1271]: Error: connect ECONNREFUSED 130.211.15.150:443 Apr 11 19:48:50 lm3886 volumio[1271]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 11 19:48:50 lm3886 volumio[1271]: errno: -111, Apr 11 19:48:50 lm3886 volumio[1271]: code: 'ECONNREFUSED', Apr 11 19:48:50 lm3886 volumio[1271]: syscall: 'connect', Apr 11 19:48:50 lm3886 volumio[1271]: address: '130.211.15.150', Apr 11 19:48:50 lm3886 volumio[1271]: port: 443 Apr 11 19:48:50 lm3886 volumio[1271]: } Apr 11 19:48:50 lm3886 volumio[1271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 19:48:50 lm3886 sudo[2878]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 19:47' Apr 11 19:48:50 lm3886 sudo[2878]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"