-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-09-14 03:08:10 CEST. -- Sep 14 03:07:01 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 03:07:01 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 03:07:01 volumio volumio[1108]: info: Discovery: Getting this device information Sep 14 03:07:01 volumio volumio[1108]: info: CoreCommandRouter::volumioGetState Sep 14 03:07:01 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:01 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 03:07:03 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand rescan Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: info: Sep 14 03:07:04 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand rescan took 4 milliseconds Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 4 milliseconds Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 1 milliseconds Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: 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} Sep 14 03:07:04 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: No code Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: ------------------------------ 102ms Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: info: Sep 14 03:07:04 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: info: Sep 14 03:07:04 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 5 milliseconds Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 4 milliseconds Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 3 milliseconds Sep 14 03:07:04 volumio volumio[1108]: info: sendMpdCommand status took 2 milliseconds Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:04 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: 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} Sep 14 03:07:04 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: No code Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: 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} Sep 14 03:07:04 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: No code Sep 14 03:07:04 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:04 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:04 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:04 volumio volumio[1108]: info: ------------------------------ 112ms Sep 14 03:07:04 volumio volumio[1108]: info: ------------------------------ 110ms Sep 14 03:07:07 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:11 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:15 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:19 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:23 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:23 volumio volumio[1108]: info: Enabling MyMusic plugin metavolumio Sep 14 03:07:23 volumio volumio[1108]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio Sep 14 03:07:23 volumio volumio[1108]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling Sep 14 03:07:23 volumio volumio[1108]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling Sep 14 03:07:23 volumio volumio[1108]: error: Could not Enable MyMusic Plugin: Error Sep 14 03:07:27 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:31 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:31 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 03:07:31 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 03:07:31 volumio volumio[1108]: info: Discovery: Getting this device information Sep 14 03:07:31 volumio volumio[1108]: info: CoreCommandRouter::volumioGetState Sep 14 03:07:31 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:31 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 03:07:35 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:37 volumio kernel: sd 0:0:0:0: [sda] tag#19 uas_eh_abort_handler 0 uas-tag 4 inflight: CMD IN Sep 14 03:07:37 volumio kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 1b 7d c6 a8 00 00 04 00 Sep 14 03:07:37 volumio kernel: sd 0:0:0:0: [sda] tag#18 uas_eh_abort_handler 0 uas-tag 2 inflight: CMD IN Sep 14 03:07:37 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x28 28 00 1b 7d ca a8 00 00 04 00 Sep 14 03:07:37 volumio kernel: xhci_hcd 0000:01:00.0: WARNING: Host System Error Sep 14 03:07:39 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:42 volumio kernel: xhci_hcd 0000:01:00.0: xHCI host not responding to stop endpoint command Sep 14 03:07:42 volumio kernel: xhci_hcd 0000:01:00.0: xHCI host controller not responding, assume dead Sep 14 03:07:42 volumio kernel: xhci_hcd 0000:01:00.0: HC died; cleaning up Sep 14 03:07:42 volumio kernel: usb 1-1: USB disconnect, device number 2 Sep 14 03:07:42 volumio kernel: usb 2-2: USB disconnect, device number 2 Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#17 uas_zap_pending 0 uas-tag 1 inflight: CMD Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 1b 7d c6 ac 00 03 fc 00 Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#17 uas_eh_abort_handler 0 uas-tag 1 inflight: Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 1b 7d c6 ac 00 03 fc 00 Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: Device offlined - not ready after error recovery Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#18 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=35s Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x28 28 00 1b 7d ca a8 00 00 04 00 Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#19 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=35s Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] tag#19 CDB: opcode=0x28 28 00 1b 7d c6 a8 00 00 04 00 Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: FAT-fs (sda1): FAT read failed (blocknr 56320) Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Sep 14 03:07:43 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Sep 14 03:07:43 volumio kernel: xhci_hcd 0000:01:00.0: WARN Can't disable streams for endpoint 0x82, streams are being disabled already Sep 14 03:07:42 volumio sudo[2199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 Sep 14 03:07:43 volumio volumio[1108]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/MUSIC /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/2834-1A05 /dev/disk/by-id/usb-JMicron_Tech_DD564198838A5-0:0-part1","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-2/2-2:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"26","ID_BUS":"usb","ID_FS_LABEL":"MUSIC","ID_FS_LABEL_ENC":"MUSIC","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"2834-1A05","ID_FS_UUID_ENC":"2834-1A05","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Tech","ID_MODEL_ENC":"Tech\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"0583","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"468862126","ID_PART_ENTRY_TYPE":"0xb","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_2_1_0-scsi-0_0_0_0","ID_REVISION":"3202","ID_SERIAL":"JMicron_Tech_DD564198838A5-0:0","ID_SERIAL_SHORT":"DD564198838A5","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"JMicron","ID_VENDOR_ENC":"JMicron\\x20","ID_VENDOR_ID":"152d","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2457","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"63947298"} Sep 14 03:07:43 volumio volumio[1108]: umount: /media/MUSIC: target is busy. Sep 14 03:07:43 volumio volumio[1108]: error: Failed to umount MUSIC: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sda1" Sep 14 03:07:43 volumio volumio[1108]: umount: /media/MUSIC: target is busy. Sep 14 03:07:42 volumio sudo[2199]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 03:07:42 volumio sudo[2199]: pam_unix(sudo:session): session closed for user root Sep 14 03:07:43 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: Sep 14 03:07:43 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 281 milliseconds Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 282 milliseconds Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 375ms Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 375ms Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 504 milliseconds Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 504 milliseconds Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 504 milliseconds Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 561ms Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 560ms Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 688 milliseconds Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 688 milliseconds Sep 14 03:07:43 volumio volumio[1108]: info: sendMpdCommand status took 689 milliseconds Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: 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} Sep 14 03:07:43 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: No code Sep 14 03:07:43 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:43 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:43 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 745ms Sep 14 03:07:43 volumio volumio[1108]: info: ------------------------------ 745ms Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:47 volumio volumio[1108]: info: Scanning removed location : "USB/MUSIC" Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 5 milliseconds Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 5 milliseconds Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 4 milliseconds Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 3 milliseconds Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 3 milliseconds Sep 14 03:07:47 volumio volumio[1108]: info: sendMpdCommand status took 3 milliseconds Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio kernel: fat__get_entry: 44234 callbacks suppressed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114474) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114475) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114476) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114477) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114478) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114479) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114480) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114481) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114482) failed Sep 14 03:07:47 volumio kernel: FAT-fs (sda1): Directory bread(block 114483) failed Sep 14 03:07:47 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio volumio[1108]: info: Command Router : Notfying DB Updatetrue Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: 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} Sep 14 03:07:47 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: No code Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: 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} Sep 14 03:07:47 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: No code Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: 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} Sep 14 03:07:47 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: No code Sep 14 03:07:47 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:47 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:47 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:47 volumio volumio[1108]: info: ------------------------------ 158ms Sep 14 03:07:47 volumio volumio[1108]: info: ------------------------------ 157ms Sep 14 03:07:47 volumio volumio[1108]: info: ------------------------------ 157ms Sep 14 03:07:47 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: MPD Database updated - AlbumList cache refreshed Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: database Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: Sep 14 03:07:47 volumio volumio[1108]: ---------------------------- MPD announces state update: update Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:47 volumio volumio[1108]: info: ControllerMpd::getState Sep 14 03:07:47 volumio volumio[1108]: verbose: ControllerMpd::sendMpdCommand status Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 46 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 46 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 46 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 38 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 38 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 37 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 37 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 36 milliseconds Sep 14 03:07:48 volumio volumio[1108]: info: sendMpdCommand status took 36 milliseconds Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: info: Command Router : Notfying DB Updatefalse Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::Close All Modals sent Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: verbose: ControllerMpd::parseState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ControllerMpd::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::servicePushState Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: 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} Sep 14 03:07:48 volumio volumio[1108]: verbose: CURRENT POSITION 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState stateService stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::syncState currentStatus stop Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: No code Sep 14 03:07:48 volumio volumio[1108]: info: CoreStateMachine::pushState Sep 14 03:07:48 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:07:48 volumio volumio[1108]: info: CoreCommandRouter::volumioPushState Sep 14 03:07:48 volumio volumio[1108]: info: ------------------------------ 237ms Sep 14 03:07:48 volumio volumio[1108]: info: ------------------------------ 230ms Sep 14 03:07:48 volumio volumio[1108]: info: ------------------------------ 229ms Sep 14 03:07:48 volumio volumio[1108]: info: ------------------------------ 228ms Sep 14 03:07:48 volumio volumio[1108]: info: ------------------------------ 229ms Sep 14 03:07:51 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:55 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:07:59 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:08:02 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 03:08:02 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 03:08:02 volumio volumio[1108]: info: Discovery: Getting this device information Sep 14 03:08:02 volumio volumio[1108]: info: CoreCommandRouter::volumioGetState Sep 14 03:08:02 volumio volumio[1108]: info: CorePlayQueue::getTrack 0 Sep 14 03:08:02 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 03:08:03 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:08:04 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 14 03:08:04 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 14 03:08:06 volumio volumio[1108]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L Russells-iMac.local. -m SMB3_11 Sep 14 03:08:06 volumio volumio[1108]: info: Error browsing device Russell’s iMac for shares: Command failed: smbclient --no-pass --debuglevel=0 -L Russells-iMac.local. -m SMB3_11 Sep 14 03:08:06 volumio volumio[1108]: Unable to initialize messaging context Sep 14 03:08:07 volumio ntpd[813]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 14 03:08:07 volumio volumio[1108]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 03:08:09 volumio volumio[1108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 03:08:09 volumio volumio[1108]: Error: dns service error: unknown Sep 14 03:08:09 volumio volumio[1108]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 14 03:08:09 volumio volumio[1108]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 14 03:08:09 volumio volumio[1108]: errorCode: -65537 Sep 14 03:08:09 volumio volumio[1108]: } Sep 14 03:08:09 volumio volumio[1108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 03:08:10 volumio sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 03:07 Sep 14 03:08:10 volumio sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"