-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-12-18 11:51:37 CST. -- Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: info: Retrieving Cloud Streaming UI Dec 18 11:50:04 volumio volumio[7278]: info: Getting Tidal Cloud Configuration Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: info: Getting Qobuz Cloud Configuration Dec 18 11:50:04 volumio volumio[7278]: info: Asking plugin for UI Config Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: info: Getting Spotify Cloud Configuration Dec 18 11:50:04 volumio volumio[7278]: info: Asking plugin for UI Config Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: info: Saving Spotify Acccount Dec 18 11:50:04 volumio volumio[7278]: info: Got Tidal Cloud Configuration Dec 18 11:50:04 volumio volumio[7278]: info: Got it Dec 18 11:50:04 volumio volumio[7278]: info: Got it Dec 18 11:50:04 volumio volumio[7278]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 18 11:50:04 volumio volumio[7278]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:50:04 volumio volumio[7278]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 11:50:05 volumio volumio[7278]: info: MyVolumio login type: Token Dec 18 11:50:05 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:50:05 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 18 11:50:09 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:50:09 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand rescan Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: info: Dec 18 11:50:09 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand rescan took 11 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: info: Dec 18 11:50:09 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:09 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: info: Dec 18 11:50:09 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:09 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: info: Dec 18 11:50:09 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 25 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 18 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 17 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 15 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 15 milliseconds Dec 18 11:50:09 volumio volumio[7278]: info: sendMpdCommand status took 13 milliseconds Dec 18 11:50:09 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 684ms Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 677ms Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 674ms Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:10 volumio volumio[7278]: info: Dec 18 11:50:10 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:10 volumio volumio[7278]: info: Dec 18 11:50:10 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 686 milliseconds Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 679 milliseconds Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 18 milliseconds Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 906ms Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 286 milliseconds Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 284 milliseconds Dec 18 11:50:10 volumio volumio[7278]: info: sendMpdCommand status took 280 milliseconds Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:50:10 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: 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} Dec 18 11:50:10 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: No code Dec 18 11:50:10 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:50:10 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:50:10 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 424ms Dec 18 11:50:10 volumio volumio[7278]: info: ------------------------------ 453ms Dec 18 11:50:13 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 18 11:50:31 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:50:31 volumio volumio[7278]: info: CURURI: music-library Dec 18 11:50:31 volumio volumio[7278]: info: Preload queue cleared Dec 18 11:50:33 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:50:33 volumio volumio[7278]: info: CURURI: music-library/USB Dec 18 11:50:33 volumio volumio[7278]: info: Preload queue cleared Dec 18 11:50:35 volumio volumio[7278]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 18 11:50:35 volumio volumio[7278]: info: Retrying Login Due to Network Error Dec 18 11:50:37 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:50:37 volumio volumio[7278]: info: CURURI: music-library/USB/mp3 Dec 18 11:50:37 volumio volumio[7278]: error: Failed LSINFO: null Dec 18 11:50:37 volumio volumio[7278]: info: Preload queue cleared Dec 18 11:50:39 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:50:39 volumio volumio[7278]: info: CURURI: music-library/USB/ϵͳ Dec 18 11:50:40 volumio volumio[7278]: error: Failed LSINFO: null Dec 18 11:50:40 volumio volumio[7278]: info: Preload queue cleared Dec 18 11:50:42 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 11:50:42 volumio volumio[7278]: info: CURURI: music-library/USB/B2D4EB95D4EB5A5D Dec 18 11:50:42 volumio volumio[7278]: error: Failed LSINFO: null Dec 18 11:50:42 volumio volumio[7278]: info: Preload queue cleared Dec 18 11:50:45 volumio volumio[7278]: info: MyVolumio login type: Token Dec 18 11:50:55 volumio kernel: usb 1-1.3: USB disconnect, device number 6 Dec 18 11:50:56 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/F0472EF4DC49C2F2 /dev/disk/by-id/usb-General_UDisk-0:0-part1 /dev/disk/by-partuuid/3ffc6d5b-01 /dev/disk/by-label/系统","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.3/1-1.3:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"29","ID_BUS":"usb","ID_FS_LABEL":"系统","ID_FS_LABEL_ENC":"系统","ID_FS_TYPE":"ntfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"F0472EF4DC49C2F2","ID_FS_UUID_ENC":"F0472EF4DC49C2F2","ID_INSTANCE":"0:0","ID_MODEL":"UDisk","ID_MODEL_ENC":"UDisk\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"1234","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"62912512","ID_PART_ENTRY_TYPE":"0x7","ID_PART_ENTRY_UUID":"3ffc6d5b-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"3ffc6d5b","ID_PATH":"platform-3f980000.usb-usb-0:1.3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_3_1_0-scsi-0_0_0_0","ID_REVISION":"5.00","ID_SERIAL":"General_UDisk-0:0","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"General","ID_VENDOR_ENC":"General\\x20","ID_VENDOR_ID":"abcd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2073","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1510844075"} Dec 18 11:51:10 volumio kernel: usb 1-1.2: new high-speed USB device number 7 using dwc_otg Dec 18 11:51:10 volumio kernel: usb 1-1.2: New USB device found, idVendor=13fe, idProduct=ffff, bcdDevice= 1.00 Dec 18 11:51:10 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Dec 18 11:51:10 volumio kernel: usb 1-1.2: Product: kingston Dec 18 11:51:10 volumio kernel: usb 1-1.2: SerialNumber: 900044911902D461 Dec 18 11:51:10 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Dec 18 11:51:10 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Dec 18 11:51:11 volumio kernel: scsi 1:0:0:0: Direct-Access kingston DataTraveler3. 0 PMAP PQ: 0 ANSI: 6 Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: [sdb] 123349248 512-byte logical blocks: (63.2 GB/58.8 GiB) Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 2b 00 00 08 Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Dec 18 11:51:14 volumio kernel: sdb: sdb1 sdb2 sdb3 sdb4 sdb5 Dec 18 11:51:14 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Dec 18 11:51:14 volumio volumio[7278]: info: Mounting Device esp Dec 18 11:51:15 volumio sudo[7871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/esp -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 18 11:51:15 volumio sudo[7871]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:15 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 18 11:51:15 volumio sudo[7871]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:15 volumio volumio[7278]: error: Cannot associate FS Label, not mounting Dec 18 11:51:15 volumio kernel: raid6: neonx8 gen() 1204 MB/s Dec 18 11:51:15 volumio volumio[7278]: info: Mounting Device efi Dec 18 11:51:15 volumio kernel: raid6: neonx4 gen() 2014 MB/s Dec 18 11:51:15 volumio sudo[7880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/efi -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Dec 18 11:51:15 volumio sudo[7880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:15 volumio kernel: FAT-fs (sdb2): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Dec 18 11:51:15 volumio sudo[7880]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:15 volumio volumio[7278]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Dec 18 11:51:15 volumio volumio[7278]: info: Retrying Login Due to Network Error Dec 18 11:51:15 volumio volumio[7278]: error: Cannot associate FS Label, not mounting Dec 18 11:51:15 volumio kernel: raid6: neonx2 gen() 1810 MB/s Dec 18 11:51:15 volumio volumio[7278]: info: Scanning new location : "USB/esp" Dec 18 11:51:15 volumio volumio[7278]: info: Scanning new location : "USB/efi" Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: Dec 18 11:51:15 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: Dec 18 11:51:15 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: Dec 18 11:51:15 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: Dec 18 11:51:15 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: Dec 18 11:51:15 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 22 milliseconds Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 18 milliseconds Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 20 milliseconds Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 18 milliseconds Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 16 milliseconds Dec 18 11:51:15 volumio volumio[7278]: info: sendMpdCommand status took 14 milliseconds Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio kernel: raid6: neonx1 gen() 1418 MB/s Dec 18 11:51:15 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:15 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: 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} Dec 18 11:51:15 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: No code Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: 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} Dec 18 11:51:15 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: No code Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio kernel: raid6: int32x8 gen() 375 MB/s Dec 18 11:51:15 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: 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} Dec 18 11:51:15 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:15 volumio volumio[7278]: info: No code Dec 18 11:51:15 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:15 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:15 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 390ms Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 388ms Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 385ms Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:16 volumio volumio[7278]: info: Dec 18 11:51:16 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:16 volumio volumio[7278]: info: Dec 18 11:51:16 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 403 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 402 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 400 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 397 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 25 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 11 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:51:16 volumio volumio[7278]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio kernel: raid6: int32x4 gen() 431 MB/s Dec 18 11:51:16 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:16 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:16 volumio kernel: raid6: int32x2 gen() 552 MB/s Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: 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} Dec 18 11:51:16 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: No code Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: 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} Dec 18 11:51:16 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: No code Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: 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} Dec 18 11:51:16 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: No code Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: 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} Dec 18 11:51:16 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio volumio[7278]: info: No code Dec 18 11:51:16 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:16 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:16 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:16 volumio kernel: raid6: int32x1 gen() 488 MB/s Dec 18 11:51:16 volumio kernel: raid6: using algorithm neonx4 gen() 2014 MB/s Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 852ms Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 852ms Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 474ms Dec 18 11:51:16 volumio volumio[7278]: info: ------------------------------ 460ms Dec 18 11:51:16 volumio kernel: raid6: .... xor() 1439 MB/s, rmw enabled Dec 18 11:51:16 volumio kernel: raid6: using neon recovery algorithm Dec 18 11:51:16 volumio kernel: xor: measuring software checksum speed Dec 18 11:51:16 volumio kernel: arm4regs : 1890 MB/sec Dec 18 11:51:16 volumio kernel: 8regs : 1357 MB/sec Dec 18 11:51:16 volumio kernel: 32regs : 1299 MB/sec Dec 18 11:51:16 volumio kernel: neon : 2114 MB/sec Dec 18 11:51:16 volumio kernel: xor: using function: neon (2114 MB/sec) Dec 18 11:51:16 volumio kernel: Btrfs loaded, zoned=no, fsverity=no Dec 18 11:51:16 volumio kernel: BTRFS: device label rootfs devid 1 transid 51 /dev/sdb3 scanned by systemd-udevd (7862) Dec 18 11:51:17 volumio volumio[7278]: info: Mounting Device rootfs Dec 18 11:51:17 volumio sudo[7912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb3 /mnt/USB/rootfs -o noatime Dec 18 11:51:17 volumio sudo[7912]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:17 volumio kernel: BTRFS info (device sdb3): first mount of filesystem 8853204a-e5fd-4b2e-b2da-d0154c07f26c Dec 18 11:51:17 volumio kernel: BTRFS info (device sdb3): using crc32c (crc32c-generic) checksum algorithm Dec 18 11:51:17 volumio kernel: BTRFS info (device sdb3): using free space tree Dec 18 11:51:17 volumio sudo[7912]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:17 volumio volumio[7278]: info: Scanning new location : "USB/rootfs" Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: info: Dec 18 11:51:17 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: info: Dec 18 11:51:17 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: info: Dec 18 11:51:17 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 21 milliseconds Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 19 milliseconds Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 18 milliseconds Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 16 milliseconds Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 14 milliseconds Dec 18 11:51:17 volumio volumio[7278]: info: sendMpdCommand status took 13 milliseconds Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:17 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: 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} Dec 18 11:51:17 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: No code Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: 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} Dec 18 11:51:17 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: No code Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: 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} Dec 18 11:51:17 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: No code Dec 18 11:51:17 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:17 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:17 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:17 volumio volumio[7278]: info: ------------------------------ 398ms Dec 18 11:51:17 volumio volumio[7278]: info: ------------------------------ 394ms Dec 18 11:51:17 volumio volumio[7278]: info: ------------------------------ 392ms Dec 18 11:51:25 volumio volumio[7278]: info: MyVolumio login type: Token Dec 18 11:51:30 volumio kernel: usb 1-1.2: USB disconnect, device number 7 Dec 18 11:51:30 volumio kernel: device offline error, dev sdb, sector 883872 op 0x0:(READ) flags 0x1000 phys_seg 4 prio class 3 Dec 18 11:51:30 volumio kernel: device offline error, dev sdb, sector 1408160 op 0x0:(READ) flags 0x1000 phys_seg 4 prio class 3 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 1, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 2, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 3, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 4, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 5, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 6, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 7, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 8, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 9, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): bdev /dev/sdb3 errs: wr 0, rd 10, flush 0, corrupt 0, gen 0 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): error loading props for ino 35134 (root 5): -5 Dec 18 11:51:30 volumio kernel: BTRFS error (device sdb3): error loading props for ino 35187 (root 5): -5 Dec 18 11:51:30 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","ACTION":"remove","DEVLINKS":"/dev/disk/by-partlabel/esp /dev/disk/by-id/usb-kingston_DataTraveler3._0_900044911902D461-0:0-part1 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1 /dev/disk/by-label/esp /dev/disk/by-uuid/6275-B47A /dev/disk/by-partuuid/45a9621a-beb7-4286-85d2-f5d7d0e30d7c","DEVNAME":"/dev/sdb1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb1","DEVTYPE":"partition","DISKSEQ":"31","ID_BUS":"usb","ID_FS_LABEL":"esp","ID_FS_LABEL_ENC":"esp","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"6275-B47A","ID_FS_UUID_ENC":"6275-B47A","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler3._0","ID_MODEL_ENC":"DataTraveler3.\\x200","ID_MODEL_ID":"ffff","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"esp","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"34","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"131038","ID_PART_ENTRY_TYPE":"c12a7328-f81f-11d2-ba4b-00a0c93ec93b","ID_PART_ENTRY_UUID":"45a9621a-beb7-4286-85d2-f5d7d0e30d7c","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f5ddc435-d84b-4748-a1b7-263434f03d53","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"kingston_DataTraveler3._0_900044911902D461-0:0","ID_SERIAL_SHORT":"900044911902D461","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"kingston","ID_VENDOR_ENC":"kingston","ID_VENDOR_ID":"13fe","MAJOR":"8","MINOR":"17","PARTN":"1","PARTNAME":"esp","SEQNUM":"2120","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1793486415"} Dec 18 11:51:31 volumio sudo[7965]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Dec 18 11:51:31 volumio sudo[7965]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:31 volumio sudo[7965]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:31 volumio kernel: FAT-fs (sdb1): unable to read boot sector to mark fs as dirty Dec 18 11:51:31 volumio systemd[1]: media-esp.mount: Succeeded. Dec 18 11:51:31 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/efi /dev/disk/by-partuuid/caf98d70-a324-4f45-99cf-10cd8c025b4f /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part2 /dev/disk/by-partlabel/efi-A /dev/disk/by-id/usb-kingston_DataTraveler3._0_900044911902D461-0:0-part2 /dev/disk/by-uuid/627A-A42B","DEVNAME":"/dev/sdb2","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb2","DEVTYPE":"partition","DISKSEQ":"31","ID_BUS":"usb","ID_FS_LABEL":"efi","ID_FS_LABEL_ENC":"efi","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"627A-A42B","ID_FS_UUID_ENC":"627A-A42B","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler3._0","ID_MODEL_ENC":"DataTraveler3.\\x200","ID_MODEL_ID":"ffff","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"efi-A","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"131072","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"262144","ID_PART_ENTRY_TYPE":"ebd0a0a2-b9e5-4433-87c0-68b6b72699c7","ID_PART_ENTRY_UUID":"caf98d70-a324-4f45-99cf-10cd8c025b4f","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f5ddc435-d84b-4748-a1b7-263434f03d53","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"kingston_DataTraveler3._0_900044911902D461-0:0","ID_SERIAL_SHORT":"900044911902D461","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"kingston","ID_VENDOR_ENC":"kingston","ID_VENDOR_ID":"13fe","MAJOR":"8","MINOR":"18","PARTN":"2","PARTNAME":"efi-A","SEQNUM":"2121","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1793532669"} Dec 18 11:51:31 volumio sudo[7968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb2 Dec 18 11:51:31 volumio sudo[7968]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:31 volumio sudo[7968]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:31 volumio systemd[1]: media-efi.mount: Succeeded. Dec 18 11:51:31 volumio kernel: FAT-fs (sdb2): unable to read boot sector to mark fs as dirty Dec 18 11:51:31 volumio volumio[7278]: info: MPD Database updated - AlbumList cache refreshed Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: database Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: MPD Database updated - AlbumList cache refreshed Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: database Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: MPD Database updated - AlbumList cache refreshed Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: database Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: Dec 18 11:51:31 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:31 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb4","ACTION":"remove","DEVLINKS":"/dev/disk/by-partlabel/var-A /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part4 /dev/disk/by-id/usb-kingston_DataTraveler3._0_900044911902D461-0:0-part4 /dev/disk/by-partuuid/5e72874f-86ad-4b67-b236-5b472494a8c5","DEVNAME":"/dev/sdb4","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb4","DEVTYPE":"partition","DISKSEQ":"31","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler3._0","ID_MODEL_ENC":"DataTraveler3.\\x200","ID_MODEL_ID":"ffff","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"var-A","ID_PART_ENTRY_NUMBER":"4","ID_PART_ENTRY_OFFSET":"11141120","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"524288","ID_PART_ENTRY_TYPE":"4d21b016-b534-45c2-a9fb-5c16e091fd2d","ID_PART_ENTRY_UUID":"5e72874f-86ad-4b67-b236-5b472494a8c5","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f5ddc435-d84b-4748-a1b7-263434f03d53","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"kingston_DataTraveler3._0_900044911902D461-0:0","ID_SERIAL_SHORT":"900044911902D461","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"kingston","ID_VENDOR_ENC":"kingston","ID_VENDOR_ID":"13fe","MAJOR":"8","MINOR":"20","PARTN":"4","PARTNAME":"var-A","SEQNUM":"2123","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1793495144"} Dec 18 11:51:31 volumio volumio[7278]: error: Cannot associate FS Label, not mounting Dec 18 11:51:31 volumio volumio[7278]: info: sendMpdCommand status took 60 milliseconds Dec 18 11:51:31 volumio volumio[7278]: info: sendMpdCommand status took 58 milliseconds Dec 18 11:51:31 volumio volumio[7278]: info: sendMpdCommand status took 50 milliseconds Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:31 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:31 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: 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} Dec 18 11:51:31 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: No code Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: 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} Dec 18 11:51:31 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: No code Dec 18 11:51:31 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:31 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:31 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:31 volumio volumio[7278]: info: ------------------------------ 296ms Dec 18 11:51:31 volumio volumio[7278]: info: ------------------------------ 296ms Dec 18 11:51:31 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb3","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-kingston_DataTraveler3._0_900044911902D461-0:0-part3 /dev/disk/by-partlabel/rootfs-A /dev/disk/by-uuid/8853204a-e5fd-4b2e-b2da-d0154c07f26c /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part3 /dev/disk/by-label/rootfs /dev/disk/by-partuuid/852a2df1-2945-45ca-b0e2-2a7fda4c22e2","DEVNAME":"/dev/sdb3","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb3","DEVTYPE":"partition","DISKSEQ":"31","ID_BTRFS_READY":"1","ID_BUS":"usb","ID_FS_LABEL":"rootfs","ID_FS_LABEL_ENC":"rootfs","ID_FS_TYPE":"btrfs","ID_FS_USAGE":"filesystem","ID_FS_UUID":"8853204a-e5fd-4b2e-b2da-d0154c07f26c","ID_FS_UUID_ENC":"8853204a-e5fd-4b2e-b2da-d0154c07f26c","ID_FS_UUID_SUB":"1520126c-b3da-4454-b480-f7549f88ef74","ID_FS_UUID_SUB_ENC":"1520126c-b3da-4454-b480-f7549f88ef74","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler3._0","ID_MODEL_ENC":"DataTraveler3.\\x200","ID_MODEL_ID":"ffff","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"rootfs-A","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"655360","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"10485760","ID_PART_ENTRY_TYPE":"4f68bce3-e8cd-4db1-96e7-fbcaf984b709","ID_PART_ENTRY_UUID":"852a2df1-2945-45ca-b0e2-2a7fda4c22e2","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f5ddc435-d84b-4748-a1b7-263434f03d53","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"kingston_DataTraveler3._0_900044911902D461-0:0","ID_SERIAL_SHORT":"900044911902D461","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"kingston","ID_VENDOR_ENC":"kingston","ID_VENDOR_ID":"13fe","MAJOR":"8","MINOR":"19","PARTN":"3","PARTNAME":"rootfs-A","SEQNUM":"2122","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1795616034"} Dec 18 11:51:31 volumio sudo[7973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb3 Dec 18 11:51:31 volumio sudo[7973]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 18 11:51:31 volumio systemd[1]: media-rootfs.mount: Succeeded. Dec 18 11:51:31 volumio kernel: BTRFS info (device sdb3): last unmount of filesystem 8853204a-e5fd-4b2e-b2da-d0154c07f26c Dec 18 11:51:31 volumio kernel: BTRFS error (device sdb3): failed to run delayed ref for logical 30408704 num_bytes 16384 type 176 action 1 ref_mod 1: -5 Dec 18 11:51:31 volumio kernel: BTRFS error (device sdb3: state A): Transaction aborted (error -5) Dec 18 11:51:31 volumio kernel: BTRFS: error (device sdb3: state A) in btrfs_run_delayed_refs:2179: errno=-5 IO failure Dec 18 11:51:31 volumio kernel: BTRFS info (device sdb3: state EA): forced readonly Dec 18 11:51:31 volumio kernel: BTRFS warning (device sdb3: state EA): Skipping commit of aborted transaction. Dec 18 11:51:31 volumio kernel: BTRFS: error (device sdb3: state EA) in cleanup_transaction:2002: errno=-5 IO failure Dec 18 11:51:31 volumio kernel: BTRFS error (device sdb3: state EA): commit super ret -5 Dec 18 11:51:32 volumio sudo[7973]: pam_unix(sudo:session): session closed for user root Dec 18 11:51:32 volumio volumio[7278]: info: sendMpdCommand status took 865 milliseconds Dec 18 11:51:32 volumio volumio[7278]: info: sendMpdCommand status took 862 milliseconds Dec 18 11:51:32 volumio volumio[7278]: info: sendMpdCommand status took 858 milliseconds Dec 18 11:51:32 volumio volumio[7278]: info: sendMpdCommand status took 851 milliseconds Dec 18 11:51:32 volumio volumio[7278]: info: sendMpdCommand status took 851 milliseconds Dec 18 11:51:32 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:32 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:32 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:32 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:32 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:32 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:32 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: 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} Dec 18 11:51:32 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: No code Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: 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} Dec 18 11:51:32 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: No code Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: 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} Dec 18 11:51:32 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: No code Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: 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} Dec 18 11:51:32 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: No code Dec 18 11:51:32 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:32 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:32 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:32 volumio volumio[7278]: info: ------------------------------ 1200ms Dec 18 11:51:32 volumio volumio[7278]: info: ------------------------------ 1200ms Dec 18 11:51:32 volumio volumio[7278]: info: ------------------------------ 1196ms Dec 18 11:51:32 volumio volumio[7278]: info: ------------------------------ 1196ms Dec 18 11:51:32 volumio volumio[7278]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb5","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part5 /dev/disk/by-partlabel/home /dev/disk/by-partuuid/e72b6629-57c6-4d61-885d-600cd1547081 /dev/disk/by-id/usb-kingston_DataTraveler3._0_900044911902D461-0:0-part5","DEVNAME":"/dev/sdb5","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.2/1-1.2:1.0/host1/target1:0:0/1:0:0:0/block/sdb/sdb5","DEVTYPE":"partition","DISKSEQ":"31","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"DataTraveler3._0","ID_MODEL_ENC":"DataTraveler3.\\x200","ID_MODEL_ID":"ffff","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NAME":"home","ID_PART_ENTRY_NUMBER":"5","ID_PART_ENTRY_OFFSET":"11665408","ID_PART_ENTRY_SCHEME":"gpt","ID_PART_ENTRY_SIZE":"3459559","ID_PART_ENTRY_TYPE":"933ac7e1-2eb4-4f13-b844-0e14e2aef915","ID_PART_ENTRY_UUID":"e72b6629-57c6-4d61-885d-600cd1547081","ID_PART_TABLE_TYPE":"gpt","ID_PART_TABLE_UUID":"f5ddc435-d84b-4748-a1b7-263434f03d53","ID_PATH":"platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_2_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"kingston_DataTraveler3._0_900044911902D461-0:0","ID_SERIAL_SHORT":"900044911902D461","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"kingston","ID_VENDOR_ENC":"kingston","ID_VENDOR_ID":"13fe","MAJOR":"8","MINOR":"21","PARTN":"5","PARTNAME":"home","SEQNUM":"2124","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"1793569829"} Dec 18 11:51:32 volumio volumio[7278]: error: Cannot associate FS Label, not mounting Dec 18 11:51:35 volumio volumio[7278]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/esp Dec 18 11:51:35 volumio volumio[7278]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/efi Dec 18 11:51:36 volumio volumio[7278]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/rootfs Dec 18 11:51:36 volumio volumio[7278]: info: Scanning removed location : "USB/efi" Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 13 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 12 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 8 milliseconds Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatetrue Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 354ms Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 351ms Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 349ms Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: Dec 18 11:51:36 volumio volumio[7278]: ---------------------------- MPD announces state update: update Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::getState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::sendMpdCommand status Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 358 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 354 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 13 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 11 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 10 milliseconds Dec 18 11:51:36 volumio volumio[7278]: info: sendMpdCommand status took 9 milliseconds Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: Command Router : Notfying DB Updatefalse Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::Close All Modals sent Dec 18 11:51:36 volumio volumio[7278]: verbose: ControllerMpd::parseState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ControllerMpd::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::servicePushState Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: 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} Dec 18 11:51:36 volumio volumio[7278]: verbose: CURRENT POSITION 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState stateService stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::syncState currentStatus stop Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: No code Dec 18 11:51:36 volumio volumio[7278]: info: CoreStateMachine::pushState Dec 18 11:51:36 volumio volumio[7278]: info: CorePlayQueue::getTrack 0 Dec 18 11:51:36 volumio volumio[7278]: info: CoreCommandRouter::volumioPushState Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 690ms Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 344ms Dec 18 11:51:36 volumio volumio[7278]: info: ------------------------------ 342ms Dec 18 11:51:36 volumio volumio[7278]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:51:36 volumio volumio[7278]: Error: connect ETIMEDOUT 199.59.149.232:443 Dec 18 11:51:36 volumio volumio[7278]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Dec 18 11:51:36 volumio volumio[7278]: errno: -110, Dec 18 11:51:36 volumio volumio[7278]: code: 'ETIMEDOUT', Dec 18 11:51:36 volumio volumio[7278]: syscall: 'connect', Dec 18 11:51:36 volumio volumio[7278]: address: '199.59.149.232', Dec 18 11:51:36 volumio volumio[7278]: port: 443 Dec 18 11:51:36 volumio volumio[7278]: } Dec 18 11:51:36 volumio volumio[7278]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 11:51:37 volumio sudo[8022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-18 11:50 Dec 18 11:51:37 volumio sudo[8022]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"