-- Logs begin at Sun 2025-03-02 14:14:31 CST, end at Sun 2025-03-02 14:17:07 CST. -- Mar 02 14:16:05 volumio kernel: usb 2-1: USB disconnect, device number 2 Mar 02 14:16:05 volumio volumio[1143]: info: Partition removed: {"syspath":"/sys/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-Generic_STORAGE_DEVICE_000000000819-0:0-part1 /dev/disk/by-path/platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/9C33-6BBD","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/axi/1000120000.pcie/1f00200000.usb/xhci-hcd.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_TYPE":"exfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9C33-6BBD","ID_FS_UUID_ENC":"9C33-6BBD","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"STORAGE_DEVICE","ID_MODEL_ENC":"STORAGE\\x20DEVICE\\x20\\x20","ID_MODEL_ID":"0747","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"131072","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"1946550272","ID_PART_ENTRY_TYPE":"0x7","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-xhci-hcd.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"0819","ID_SERIAL":"Generic_STORAGE_DEVICE_000000000819-0:0","ID_SERIAL_SHORT":"000000000819","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Generic","ID_VENDOR_ENC":"Generic\\x20","ID_VENDOR_ID":"05e3","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"3090","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3343377"} Mar 02 14:16:05 volumio sudo[1597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Mar 02 14:16:05 volumio systemd[1]: media-9C33\x2d6BBD.mount: Succeeded. Mar 02 14:16:05 volumio sudo[1597]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:05 volumio sudo[1597]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:05 volumio mount.exfat[1206]: failed to write super block Mar 02 14:16:07 volumio volumio[1143]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 02 14:16:07 volumio volumio[1143]: info: Retrying Login Due to Network Error Mar 02 14:16:09 volumio volumio[1143]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/9C33-6BBD Mar 02 14:16:10 volumio kernel: usb 1-2: new high-speed USB device number 2 using xhci-hcd Mar 02 14:16:10 volumio kernel: usb 1-2: New USB device found, idVendor=05e3, idProduct=0747, bcdDevice= 8.19 Mar 02 14:16:10 volumio kernel: usb 1-2: New USB device strings: Mfr=3, Product=4, SerialNumber=5 Mar 02 14:16:10 volumio kernel: usb 1-2: Product: USB Storage Mar 02 14:16:10 volumio kernel: usb 1-2: Manufacturer: Generic Mar 02 14:16:10 volumio kernel: usb 1-2: SerialNumber: 000000000819 Mar 02 14:16:10 volumio kernel: usb-storage 1-2:1.0: USB Mass Storage device detected Mar 02 14:16:10 volumio kernel: scsi host0: usb-storage 1-2:1.0 Mar 02 14:16:10 volumio volumio[1143]: info: Scanning removed location : "USB/9C33-6BBD" Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 3 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 64ms Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 64ms Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 63ms Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: Mar 02 14:16:10 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: info: sendMpdCommand status took 4 milliseconds Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:10 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: 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} Mar 02 14:16:10 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: No code Mar 02 14:16:10 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:10 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:10 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 47ms Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 46ms Mar 02 14:16:10 volumio volumio[1143]: info: ------------------------------ 46ms Mar 02 14:16:11 volumio kernel: scsi 0:0:0:0: Direct-Access Generic STORAGE DEVICE 0819 PQ: 0 ANSI: 6 Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: Attached scsi generic sg0 type 0 Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: [sda] 1946681344 512-byte logical blocks: (997 GB/928 GiB) Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: [sda] Mode Sense: 87 00 00 00 Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Mar 02 14:16:12 volumio kernel: sda: sda1 Mar 02 14:16:12 volumio kernel: sd 0:0:0:0: [sda] Attached SCSI removable disk Mar 02 14:16:12 volumio volumio[1143]: info: Mounting Device 9C33-6BBD Mar 02 14:16:12 volumio sudo[1640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/9C33-6BBD -o noatime Mar 02 14:16:12 volumio sudo[1640]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:12 volumio mount.exfat[1642]: volume was not unmounted cleanly Mar 02 14:16:12 volumio sudo[1640]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:12 volumio volumio[1143]: WARN: volume was not unmounted cleanly. Mar 02 14:16:12 volumio volumio[1143]: info: Scanning new location : "USB/9C33-6BBD" Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: info: Mar 02 14:16:12 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: info: Mar 02 14:16:12 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: info: Mar 02 14:16:12 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 3 milliseconds Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 02 14:16:12 volumio volumio[1143]: info: sendMpdCommand status took 1 milliseconds Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: info: Command Router : Notfying DB Updatetrue Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:12 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: 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} Mar 02 14:16:12 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: No code Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: 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} Mar 02 14:16:12 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: No code Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: 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} Mar 02 14:16:12 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: No code Mar 02 14:16:12 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:12 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:12 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:12 volumio volumio[1143]: info: ------------------------------ 66ms Mar 02 14:16:12 volumio volumio[1143]: info: ------------------------------ 65ms Mar 02 14:16:12 volumio volumio[1143]: info: ------------------------------ 64ms Mar 02 14:16:13 volumio volumio[1143]: info: MPD Database updated - AlbumList cache refreshed Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: database Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: MPD Database updated - AlbumList cache refreshed Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: database Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 10 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 11 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 10 milliseconds Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 43ms Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 43ms Mar 02 14:16:13 volumio volumio[1143]: info: MPD Database updated - AlbumList cache refreshed Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: database Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: Mar 02 14:16:13 volumio volumio[1143]: ---------------------------- MPD announces state update: update Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 55 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 55 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 55 milliseconds Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 80ms Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 80ms Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 41 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 41 milliseconds Mar 02 14:16:13 volumio volumio[1143]: info: sendMpdCommand status took 41 milliseconds Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: Command Router : Notfying DB Updatefalse Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::Close All Modals sent Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: 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} Mar 02 14:16:13 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState stateService stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: No code Mar 02 14:16:13 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:13 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:13 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 65ms Mar 02 14:16:13 volumio volumio[1143]: info: ------------------------------ 66ms Mar 02 14:16:17 volumio volumio[1143]: info: MyVolumio login type: Token Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPlay Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::play index undefined Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::startPlaybackTimer Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::clearAddPlayTracks USB/9C33-6BBD/陈奕迅 - 碌卡.flac Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand stop Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand stop took 0 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand clear Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand clear took 0 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand add "USB/9C33-6BBD/陈奕迅 - 碌卡.flac" Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand add "USB/9C33-6BBD/陈奕迅 - 碌卡.flac" took 1 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand play Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 2ms Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand play took 2 milliseconds Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 1ms Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 3ms Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 2ms Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces system playlist update Mar 02 14:16:19 volumio volumio[1143]: info: Ignoring MPD Status Update Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand status took 2 milliseconds Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 2ms Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 0 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"碌卡","artist":"陈奕迅","album":"…3mm","uri":"USB/9C33-6BBD/陈奕迅 - 碌卡.flac","trackType":"flac"} Mar 02 14:16:19 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState stateService play Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus stop Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 5ms Mar 02 14:16:19 volumio volumio[1143]: info: Mar 02 14:16:19 volumio volumio[1143]: ---------------------------- MPD announces state update: player Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::getState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand status Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 2 milliseconds Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand status took 0 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseState Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"304 Kbps","isStreaming":false,"title":"碌卡","artist":"陈奕迅","album":"…3mm","uri":"USB/9C33-6BBD/陈奕迅 - 碌卡.flac","trackType":"flac"} Mar 02 14:16:19 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState stateService play Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus play Mar 02 14:16:19 volumio volumio[1143]: info: Received an update from plugin. extracting info from payload Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 12ms Mar 02 14:16:19 volumio volumio[1143]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 02 14:16:19 volumio volumio[1143]: verbose: ControllerMpd::parseTrackInfo Mar 02 14:16:19 volumio volumio[1143]: info: ControllerMpd::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::servicePushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":216,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1006 Kbps","isStreaming":false,"title":"碌卡","artist":"陈奕迅","album":"…3mm","uri":"USB/9C33-6BBD/陈奕迅 - 碌卡.flac","trackType":"flac"} Mar 02 14:16:19 volumio volumio[1143]: verbose: CURRENT POSITION 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState stateService play Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::syncState currentStatus play Mar 02 14:16:19 volumio volumio[1143]: info: Received an update from plugin. extracting info from payload Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:19 volumio volumio[1143]: info: CoreStateMachine::pushState Mar 02 14:16:19 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:19 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Mar 02 14:16:19 volumio volumio[1143]: info: ------------------------------ 14ms Mar 02 14:16:29 volumio volumio-remote-updater[731]: No test mode Mar 02 14:16:29 volumio volumio-remote-updater[731]: No alpha test mode Mar 02 14:16:29 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 02 14:16:30 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 02 14:16:30 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 02 14:16:45 volumio sudo[1813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 14:16:45 volumio sudo[1813]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:45 volumio sudo[1813]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:45 volumio sudo[1815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 14:16:45 volumio sudo[1815]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:45 volumio sudo[1815]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:45 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.137.98 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 02 14:16:45 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 14:16:45 volumio sudo[1821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 02 14:16:45 volumio sudo[1821]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:45 volumio sudo[1821]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:45 volumio sudo[1824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 02 14:16:45 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 02 14:16:45 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Mar 02 14:16:45 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.137.98 from 192.168.137.1 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Edg/133.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 02 14:16:45 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 02 14:16:45 volumio volumio[1143]: info: Listing playlists Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 02 14:16:45 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 02 14:16:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 02 14:16:47 volumio volumio[1143]: info: Received Get System Info Mar 02 14:16:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 02 14:16:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 02 14:16:47 volumio volumio[1143]: info: Discovery: Getting this device information Mar 02 14:16:47 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Mar 02 14:16:47 volumio volumio[1143]: info: CorePlayQueue::getTrack 0 Mar 02 14:16:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 02 14:16:47 volumio volumio[1143]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Mar 02 14:16:47 volumio volumio[1143]: info: Retrying Login Due to Network Error Mar 02 14:16:49 volumio ntpd[961]: Soliciting pool server 139.178.66.41 Mar 02 14:16:51 volumio ntpd[961]: Soliciting pool server 162.159.200.123 Mar 02 14:16:51 volumio ntpd[961]: Soliciting pool server 172.105.240.243 Mar 02 14:16:52 volumio ntpd[961]: Soliciting pool server 147.75.202.161 Mar 02 14:16:52 volumio ntpd[961]: Soliciting pool server 217.144.132.253 Mar 02 14:16:57 volumio volumio[1143]: info: MyVolumio login type: Token Mar 02 14:17:07 volumio volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 14:17:07 volumio volumio[1143]: Error: connect ETIMEDOUT 128.121.243.228:443 Mar 02 14:17:07 volumio volumio[1143]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Mar 02 14:17:07 volumio volumio[1143]: errno: -110, Mar 02 14:17:07 volumio volumio[1143]: code: 'ETIMEDOUT', Mar 02 14:17:07 volumio volumio[1143]: syscall: 'connect', Mar 02 14:17:07 volumio volumio[1143]: address: '128.121.243.228', Mar 02 14:17:07 volumio volumio[1143]: port: 443 Mar 02 14:17:07 volumio volumio[1143]: } Mar 02 14:17:07 volumio volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 02 14:17:07 volumio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-02 14:16 Mar 02 14:17:07 volumio sudo[1927]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"