-- Logs begin at Thu 2019-02-14 18:12:00 +08, end at Mon 2024-07-15 09:28:42 +08. -- Jul 15 09:27:01 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jul 15 09:27:01 volumio volumio[921]: info: CURURI: playlists/y Jul 15 09:27:01 volumio volumio[921]: info: Preload queue cleared Jul 15 09:27:01 volumio volumio[921]: info: Preloading song: mnt/USB/0E0E-F3F2/aa/天长地久 - 周启生.mp3 Jul 15 09:27:01 volumio volumio[921]: info: Exploding uri mnt/USB/0E0E-F3F2/aa/天长地久 - 周启生.mp3 in service mpd Jul 15 09:27:01 volumio volumio[921]: error: scanFolder - failure to stat '/mnt/USB/0E0E-F3F2/aa/天长地久 - 周启生.mp3' Jul 15 09:27:05 volumio volumio[921]: info: Preload queue cleared Jul 15 09:27:05 volumio volumio[921]: info: CoreCommandRouter::volumioReplaceandPlayItems Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::ClearQueue Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::stop Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::clearPlayQueue Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::saveQueue Jul 15 09:27:05 volumio volumio[921]: info: CoreCommandRouter::volumioPushQueue Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::addQueueItems Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::addQueueItems Jul 15 09:27:05 volumio volumio[921]: info: Preload queue cleared Jul 15 09:27:05 volumio volumio[921]: info: Adding Item to queue: mnt/USB/0E0E-F3F2/aa/天长地久 - 周启生.mp3 Jul 15 09:27:05 volumio volumio[921]: info: Using cached record of: mnt/USB/0E0E-F3F2/aa/天长地久 - 周启生.mp3 Jul 15 09:27:05 volumio volumio[921]: info: CoreCommandRouter::volumioPushQueue Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::saveQueue Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::updateTrackBlock Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::getTrackBlock Jul 15 09:27:05 volumio volumio[921]: info: CoreCommandRouter::volumioPlay Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::play index 0 Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::stop Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::play index undefined Jul 15 09:27:05 volumio volumio[921]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 15 09:27:05 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 15 09:27:32 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 15 09:27:36 volumio kernel: usb 1-1.2: USB disconnect, device number 18 Jul 15 09:27:36 volumio volumio[921]: 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-id/usb-Mass_Storage_Device_121220130416-0:0-part1 /dev/disk/by-uuid/080B-0044 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","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":"42","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"080B-0044","ID_FS_UUID_ENC":"080B-0044","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"135","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3854201","ID_PART_ENTRY_TYPE":"0x6","ID_PART_TABLE_TYPE":"dos","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":"1.00","ID_SERIAL":"Mass_Storage_Device_121220130416-0:0","ID_SERIAL_SHORT":"121220130416","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2237","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"335668921"} Jul 15 09:27:36 volumio sudo[1843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Jul 15 09:27:36 volumio sudo[1843]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:27:36 volumio sudo[1843]: pam_unix(sudo:session): session closed for user root Jul 15 09:27:40 volumio volumio[921]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/080B-0044 Jul 15 09:27:41 volumio volumio[921]: info: Scanning removed location : "USB/080B-0044" Jul 15 09:27:41 volumio kernel: fat__get_entry: 14 callbacks suppressed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3796) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3797) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3798) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3799) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3800) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3801) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3802) failed Jul 15 09:27:41 volumio kernel: FAT-fs (sda1): Directory bread(block 3803) failed Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 16 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 9 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 8 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 7 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 6 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 5 milliseconds Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: Jul 15 09:27:41 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 147 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 146 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 93 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 72 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 52 milliseconds Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 271ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 264ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 263ms Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 188 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 183 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 183 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 181 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 176 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand status took 176 milliseconds Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 190 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 191 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 15 09:27:41 volumio volumio[921]: info: sendMpdCommand playlistinfo took 12 milliseconds Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:27:41 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: No code Jul 15 09:27:41 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:27:41 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:27:41 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 496ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 497ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 382ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 383ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 376ms Jul 15 09:27:41 volumio volumio[921]: info: ------------------------------ 377ms Jul 15 09:27:42 volumio kernel: usb 1-1.2: new high-speed USB device number 19 using dwc_otg Jul 15 09:27:42 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:27:42 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:27:42 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:27:42 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:27:42 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:27:42 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:27:42 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:27:43 volumio kernel: scsi 1:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] 3854336 512-byte logical blocks: (1.97 GB/1.84 GiB) Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Jul 15 09:27:43 volumio kernel: sdb: sdb1 Jul 15 09:27:43 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Jul 15 09:27:44 volumio volumio[921]: info: Mounting Device 080B-0044 Jul 15 09:27:44 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/080B-0044 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 15 09:27:44 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:27:44 volumio kernel: usb 1-1.2: reset high-speed USB device number 19 using dwc_otg Jul 15 09:27:44 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Jul 15 09:27:44 volumio volumio[921]: mount: /media/080B-0044: special device /dev/sdb1 does not exist. Jul 15 09:27:44 volumio kernel: usb 1-1.2: device firmware changed Jul 15 09:27:44 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:27:44 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 3a ce 77 00 00 10 00 Jul 15 09:27:44 volumio kernel: blk_print_req_error: 10 callbacks suppressed Jul 15 09:27:44 volumio kernel: I/O error, dev sdb, sector 3853943 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Jul 15 09:27:44 volumio kernel: usb 1-1.2: USB disconnect, device number 19 Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853568, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853569, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853570, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853571, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853572, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853573, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853574, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853575, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 4096, async page read Jul 15 09:27:44 volumio kernel: Buffer I/O error on dev sdb1, logical block 4097, async page read Jul 15 09:27:44 volumio kernel: ext3: Unknown parameter 'dmask' Jul 15 09:27:44 volumio kernel: ext2: Unknown parameter 'dmask' Jul 15 09:27:44 volumio kernel: ext4: Unknown parameter 'dmask' Jul 15 09:27:44 volumio volumio[921]: error: Failed to mount 080B-0044: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sdb1" "/mnt/USB/080B-0044" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 15 09:27:44 volumio volumio[921]: mount: /media/080B-0044: special device /dev/sdb1 does not exist. Jul 15 09:27:44 volumio volumio[921]: 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-id/usb-Mass_Storage_Device_121220130416-0:0-part1 /dev/disk/by-uuid/080B-0044 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","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":"43","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"080B-0044","ID_FS_UUID_ENC":"080B-0044","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"135","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3854201","ID_PART_ENTRY_TYPE":"0x6","ID_PART_TABLE_TYPE":"dos","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":"1.00","ID_SERIAL":"Mass_Storage_Device_121220130416-0:0","ID_SERIAL_SHORT":"121220130416","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2271","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"431748673"} Jul 15 09:27:44 volumio sudo[1876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Jul 15 09:27:44 volumio sudo[1876]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:27:44 volumio sudo[1876]: pam_unix(sudo:session): session closed for user root Jul 15 09:27:44 volumio volumio[921]: umount: /dev/sdb1: no mount point specified. Jul 15 09:27:44 volumio volumio[921]: error: Failed to umount 080B-0044: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdb1" Jul 15 09:27:44 volumio volumio[921]: umount: /dev/sdb1: no mount point specified. Jul 15 09:27:45 volumio kernel: usb 1-1.2: new high-speed USB device number 20 using dwc_otg Jul 15 09:27:45 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:27:45 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:27:45 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:27:45 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:27:45 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:27:45 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:27:45 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:27:45 volumio ntpd[754]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Jul 15 09:27:46 volumio kernel: usb 1-1.2: USB disconnect, device number 20 Jul 15 09:27:46 volumio kernel: usb 1-1.2: new high-speed USB device number 21 using dwc_otg Jul 15 09:27:46 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:27:46 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:27:46 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:27:46 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:27:46 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:27:46 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:27:46 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:27:48 volumio kernel: usb 1-1.2: reset high-speed USB device number 21 using dwc_otg Jul 15 09:27:49 volumio kernel: usb 1-1.2: device descriptor read/all, error -71 Jul 15 09:27:50 volumio kernel: usb 1-1.2: USB disconnect, device number 21 Jul 15 09:27:52 volumio kernel: usb 1-1.2: new high-speed USB device number 22 using dwc_otg Jul 15 09:27:52 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:27:52 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:27:52 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:27:52 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:27:52 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:27:52 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:27:52 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:27:54 volumio kernel: usb 1-1.2: USB disconnect, device number 22 Jul 15 09:28:06 volumio kernel: usb 1-1.2: new high-speed USB device number 23 using dwc_otg Jul 15 09:28:06 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:28:06 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:28:06 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:28:06 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:28:06 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:28:06 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:28:06 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:28:08 volumio kernel: usb 1-1.2: reset high-speed USB device number 23 using dwc_otg Jul 15 09:28:09 volumio kernel: usb 1-1.2: reset high-speed USB device number 23 using dwc_otg Jul 15 09:28:10 volumio kernel: usb 1-1.2: USB disconnect, device number 23 Jul 15 09:28:11 volumio kernel: usb 1-1.2: new high-speed USB device number 24 using dwc_otg Jul 15 09:28:11 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:28:11 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:28:11 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:28:11 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:28:11 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:28:11 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:28:11 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:28:12 volumio kernel: scsi 1:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] 3854336 512-byte logical blocks: (1.97 GB/1.84 GiB) Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Jul 15 09:28:12 volumio kernel: sdb: sdb1 Jul 15 09:28:12 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Jul 15 09:28:13 volumio kernel: usb 1-1.2: reset high-speed USB device number 24 using dwc_otg Jul 15 09:28:13 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:28:13 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 3a cf 07 00 00 08 00 Jul 15 09:28:13 volumio kernel: I/O error, dev sdb, sector 3854087 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Jul 15 09:28:13 volumio kernel: usb 1-1.2: reset high-speed USB device number 24 using dwc_otg Jul 15 09:28:13 volumio kernel: usb 1-1.2: reset high-speed USB device number 24 using dwc_otg Jul 15 09:28:13 volumio kernel: usb 1-1.2: device firmware changed Jul 15 09:28:13 volumio kernel: usb 1-1.2: USB disconnect, device number 24 Jul 15 09:28:13 volumio volumio[921]: error: Cannot associate FS Label, not mounting Jul 15 09:28:13 volumio volumio[921]: 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-id/usb-Mass_Storage_Device_121220130416-0:0-part1 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","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":"44","ID_BUS":"usb","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_TABLE_TYPE":"dos","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":"1.00","ID_SERIAL":"Mass_Storage_Device_121220130416-0:0","ID_SERIAL_SHORT":"121220130416","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2361","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"461430978"} Jul 15 09:28:13 volumio volumio[921]: error: Cannot associate FS Label, not mounting Jul 15 09:28:13 volumio kernel: buffer_io_error: 6 callbacks suppressed Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853952, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853953, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853954, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853955, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853956, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853957, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853958, async page read Jul 15 09:28:13 volumio kernel: Buffer I/O error on dev sdb1, logical block 3853959, async page read Jul 15 09:28:13 volumio kernel: usb 1-1.2: new high-speed USB device number 25 using dwc_otg Jul 15 09:28:14 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:28:14 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:28:14 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:28:14 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:28:14 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:28:14 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:28:14 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:28:15 volumio kernel: scsi 1:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] 3854336 512-byte logical blocks: (1.97 GB/1.84 GiB) Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Jul 15 09:28:15 volumio kernel: sdb: sdb1 Jul 15 09:28:15 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Jul 15 09:28:15 volumio volumio[921]: info: Mounting Device 080B-0044 Jul 15 09:28:15 volumio sudo[1950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/080B-0044 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 15 09:28:15 volumio sudo[1950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:15 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 15 09:28:15 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 15 09:28:15 volumio sudo[1950]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:15 volumio volumio[921]: info: Scanning new location : "USB/080B-0044" Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: info: Jul 15 09:28:15 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: info: Jul 15 09:28:15 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: info: Jul 15 09:28:15 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 11 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 10 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 9 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 7 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 7 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand status took 6 milliseconds Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: info: Command Router : Notfying DB Updatetrue Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: info: Command Router : Notfying DB Updatetrue Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: info: Command Router : Notfying DB Updatetrue Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand playlistinfo took 63 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand playlistinfo took 38 milliseconds Jul 15 09:28:15 volumio volumio[921]: info: sendMpdCommand playlistinfo took 16 milliseconds Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:15 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:15 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: No code Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:15 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: No code Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:15 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: No code Jul 15 09:28:15 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:15 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:15 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:15 volumio volumio[921]: info: ------------------------------ 204ms Jul 15 09:28:15 volumio volumio[921]: info: ------------------------------ 208ms Jul 15 09:28:15 volumio volumio[921]: info: ------------------------------ 206ms Jul 15 09:28:16 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:16 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:28:16 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 22 3a bb 00 00 07 00 Jul 15 09:28:16 volumio kernel: I/O error, dev sdb, sector 2243259 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 3 Jul 15 09:28:16 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:16 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:16 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:17 volumio kernel: Jul 15 09:28:17 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5 Jul 15 09:28:17 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 24 16 fb 00 00 f0 00 Jul 15 09:28:17 volumio kernel: I/O error, dev sdb, sector 2365179 op 0x0:(READ) flags 0x84700 phys_seg 3 prio class 3 Jul 15 09:28:17 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] Unaligned partial completion (resid=7682, sector_sz=512) Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 24 17 eb 00 00 10 00 Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:28:17 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 24 17 eb 00 00 10 00 Jul 15 09:28:17 volumio kernel: I/O error, dev sdb, sector 2365419 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 3 Jul 15 09:28:17 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:18 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:19 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:20 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:21 volumio kernel: usb 1-1.2: device descriptor read/64, error -71 Jul 15 09:28:22 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:22 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:22 volumio kernel: usb 1-1.2: reset high-speed USB device number 25 using dwc_otg Jul 15 09:28:22 volumio kernel: usb 1-1.2: USB disconnect, device number 25 Jul 15 09:28:22 volumio kernel: sdb: detected capacity change from 3854336 to 0 Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2365179 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2365179 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: device offline error, dev sdb, sector 2474 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2339) Jul 15 09:28:22 volumio volumio[921]: 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-id/usb-Mass_Storage_Device_121220130416-0:0-part1 /dev/disk/by-uuid/080B-0044 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","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":"46","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"080B-0044","ID_FS_UUID_ENC":"080B-0044","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"135","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3854201","ID_PART_ENTRY_TYPE":"0x6","ID_PART_TABLE_TYPE":"dos","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":"1.00","ID_SERIAL":"Mass_Storage_Device_121220130416-0:0","ID_SERIAL_SHORT":"121220130416","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2396","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"463034019"} Jul 15 09:28:23 volumio sudo[1966]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Jul 15 09:28:23 volumio sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:23 volumio sudo[1966]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:23 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: Jul 15 09:28:23 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 64 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 63 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 63 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 62 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 62 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 61 milliseconds Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 164 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 164 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand status took 164 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 54 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 33 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 34 milliseconds Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio kernel: usb 1-1.2: new high-speed USB device number 26 using dwc_otg Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 291ms Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 292ms Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 291ms Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 291ms Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 100 milliseconds Jul 15 09:28:23 volumio volumio[921]: info: sendMpdCommand playlistinfo took 101 milliseconds Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: verbose: ControllerMpd::parseTrackInfo Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream2.mp3","artist":null,"album":null,"uri":"http://119.15.96.188/stream2.mp3","trackType":"mp3"} Jul 15 09:28:23 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: No code Jul 15 09:28:23 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:23 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:23 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 337ms Jul 15 09:28:23 volumio volumio[921]: info: ------------------------------ 338ms Jul 15 09:28:23 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:28:23 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:28:23 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:28:23 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:28:23 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:28:23 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:28:23 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:28:24 volumio kernel: usb 1-1.2: reset high-speed USB device number 26 using dwc_otg Jul 15 09:28:24 volumio volumio[921]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Jul 15 09:28:24 volumio sudo[1978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 15 09:28:24 volumio sudo[1978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:24 volumio sudo[1978]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:24 volumio volumio[921]: info: MPD Permissions set Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 15 09:28:24 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 15 09:28:24 volumio systemd[1]: Stopping Music Player Daemon... Jul 15 09:28:24 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 15 09:28:24 volumio kernel: scsi 1:0:0:0: Direct-Access Mass Storage Device 1.00 PQ: 0 ANSI: 0 CCS Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] 3854336 512-byte logical blocks: (1.97 GB/1.84 GiB) Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 03 00 00 00 Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] No Caching mode page found Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] Assuming drive cache: write through Jul 15 09:28:24 volumio kernel: sdb: sdb1 Jul 15 09:28:24 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Jul 15 09:28:25 volumio systemd[1]: mpd.service: Succeeded. Jul 15 09:28:25 volumio systemd[1]: Stopped Music Player Daemon. Jul 15 09:28:25 volumio systemd[1]: Starting Music Player Daemon... Jul 15 09:28:25 volumio sudo[1987]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 15 09:28:25 volumio sudo[1987]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:25 volumio sudo[1987]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:25 volumio volumio[921]: info: Mounting Device 080B-0044 Jul 15 09:28:25 volumio sudo[1997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/080B-0044 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 15 09:28:25 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:25 volumio kernel: FAT-fs (sdb1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Jul 15 09:28:25 volumio kernel: FAT-fs (sdb1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Jul 15 09:28:25 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:26 volumio mpd[1996]: Jul 15 09:28 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 15 09:28:26 volumio systemd[1]: Started Music Player Daemon. Jul 15 09:28:26 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:26 volumio volumio[921]: error: updateQueue error: null Jul 15 09:28:27 volumio volumio[921]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/080B-0044 Jul 15 09:28:28 volumio volumio[921]: info: Scanning removed location : "USB/080B-0044" Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:28 volumio volumio[921]: info: Jul 15 09:28:28 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:28 volumio volumio[921]: info: sendMpdCommand status took 2 milliseconds Jul 15 09:28:28 volumio volumio[921]: info: sendMpdCommand status took 1 milliseconds Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:28 volumio volumio[921]: info: Command Router : Notfying DB Updatetrue Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: 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} Jul 15 09:28:28 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: No code Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: ------------------------------ 76ms Jul 15 09:28:28 volumio volumio[921]: 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-id/usb-Mass_Storage_Device_121220130416-0:0-part1 /dev/disk/by-uuid/080B-0044 /dev/disk/by-path/platform-3f980000.usb-usb-0:1.2:1.0-scsi-0:0:0:0-part1","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":"47","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"080B-0044","ID_FS_UUID_ENC":"080B-0044","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Storage_Device","ID_MODEL_ENC":"Storage\\x20Device\\x20\\x20","ID_MODEL_ID":"1212","ID_PART_ENTRY_DISK":"8:16","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"135","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"3854201","ID_PART_ENTRY_TYPE":"0x6","ID_PART_TABLE_TYPE":"dos","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":"1.00","ID_SERIAL":"Mass_Storage_Device_121220130416-0:0","ID_SERIAL_SHORT":"121220130416","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"Mass","ID_VENDOR_ENC":"Mass\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"14cd","MAJOR":"8","MINOR":"17","PARTN":"1","SEQNUM":"2430","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"472894942"} Jul 15 09:28:28 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Jul 15 09:28:28 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 24 aa d3 00 00 06 00 Jul 15 09:28:28 volumio kernel: blk_print_req_error: 23 callbacks suppressed Jul 15 09:28:28 volumio kernel: I/O error, dev sdb, sector 2403027 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: usb 1-1.2: USB disconnect, device number 26 Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2403027 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2403035 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 3 Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: device offline error, dev sdb, sector 2510 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 3 Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio kernel: FAT-fs (sdb1): FAT read failed (blocknr 2375) Jul 15 09:28:28 volumio sudo[2007]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdb1 Jul 15 09:28:28 volumio sudo[2007]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 15 09:28:28 volumio sudo[2007]: pam_unix(sudo:session): session closed for user root Jul 15 09:28:28 volumio volumio[921]: info: MPD Database updated - AlbumList cache refreshed Jul 15 09:28:28 volumio volumio[921]: info: Jul 15 09:28:28 volumio volumio[921]: ---------------------------- MPD announces state update: database Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:28 volumio volumio[921]: info: Jul 15 09:28:28 volumio volumio[921]: ---------------------------- MPD announces state update: update Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::getState Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::sendMpdCommand status Jul 15 09:28:28 volumio volumio[921]: info: sendMpdCommand status took 27 milliseconds Jul 15 09:28:28 volumio volumio[921]: info: sendMpdCommand status took 26 milliseconds Jul 15 09:28:28 volumio volumio[921]: info: sendMpdCommand status took 26 milliseconds Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:28 volumio volumio[921]: info: Command Router : Notfying DB Updatefalse Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::Close All Modals sent Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:28 volumio volumio[921]: verbose: ControllerMpd::parseState Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: 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} Jul 15 09:28:28 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: No code Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: ControllerMpd::pushState Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::servicePushState Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: 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} Jul 15 09:28:28 volumio volumio[921]: verbose: CURRENT POSITION 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState stateService stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::syncState currentStatus stop Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: No code Jul 15 09:28:28 volumio volumio[921]: info: CoreStateMachine::pushState Jul 15 09:28:28 volumio volumio[921]: info: CorePlayQueue::getTrack 0 Jul 15 09:28:28 volumio volumio[921]: info: CoreCommandRouter::volumioPushState Jul 15 09:28:28 volumio volumio[921]: info: ------------------------------ 110ms Jul 15 09:28:28 volumio volumio[921]: info: ------------------------------ 110ms Jul 15 09:28:28 volumio kernel: usb 1-1.2: new high-speed USB device number 27 using dwc_otg Jul 15 09:28:28 volumio kernel: usb 1-1.2: New USB device found, idVendor=14cd, idProduct=1212, bcdDevice= 1.00 Jul 15 09:28:28 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=2 Jul 15 09:28:28 volumio kernel: usb 1-1.2: Product: Mass Storage Device Jul 15 09:28:28 volumio kernel: usb 1-1.2: Manufacturer: Generic Jul 15 09:28:28 volumio kernel: usb 1-1.2: SerialNumber: 121220130416 Jul 15 09:28:28 volumio kernel: usb-storage 1-1.2:1.0: USB Mass Storage device detected Jul 15 09:28:28 volumio kernel: scsi host1: usb-storage 1-1.2:1.0 Jul 15 09:28:30 volumio kernel: usb 1-1.2: reset high-speed USB device number 27 using dwc_otg Jul 15 09:28:31 volumio kernel: usb 1-1.2: reset high-speed USB device number 27 using dwc_otg Jul 15 09:28:31 volumio kernel: usb 1-1.2: reset high-speed USB device number 27 using dwc_otg Jul 15 09:28:32 volumio volumio[921]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/080B-0044 Jul 15 09:28:32 volumio kernel: usb 1-1.2: reset high-speed USB device number 27 using dwc_otg Jul 15 09:28:34 volumio kernel: usb 1-1.2: USB disconnect, device number 27 Jul 15 09:28:41 volumio volumio[921]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 09:28:41 volumio volumio[921]: Error: connect ETIMEDOUT 157.240.13.8:443 Jul 15 09:28:41 volumio volumio[921]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 15 09:28:41 volumio volumio[921]: errno: -110, Jul 15 09:28:41 volumio volumio[921]: code: 'ETIMEDOUT', Jul 15 09:28:41 volumio volumio[921]: syscall: 'connect', Jul 15 09:28:41 volumio volumio[921]: address: '157.240.13.8', Jul 15 09:28:41 volumio volumio[921]: port: 443 Jul 15 09:28:41 volumio volumio[921]: } Jul 15 09:28:41 volumio volumio[921]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 15 09:28:42 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-15 09:27 Jul 15 09:28:42 volumio sudo[2026]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"