-- Logs begin at Tue 2025-07-08 10:33:13 CST, end at Tue 2025-07-08 11:47:33 CST. -- Jul 08 11:46:03 volumio volumio[1245]: info: MyVolumio login type: Token Jul 08 11:46:17 volumio ntpd[13308]: 193.182.111.12 local addr 192.168.0.101 -> Jul 08 11:46:33 volumio volumio[1245]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 08 11:46:33 volumio volumio[1245]: info: Retrying Login Due to Network Error Jul 08 11:46:42 volumio kernel: usb 3-3: USB disconnect, device number 5 Jul 08 11:46:42 volumio volumio[1245]: info: Partition removed: {"syspath":"/sys/devices/pci0000:00/0000:00:13.2/usb3/3-3/3-3:1.0/host3/target3:0:0/3:0:0:0/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/pci-0000:00:13.2-usb-0:3:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/E4EF-DEA7 /dev/disk/by-id/usb-USB_Sandisk_3.2Gen1_0401ecef02828dbc350de682e3717dff1676f13dbda49d095f73720475bb7d9430e0000000000000000000002087c63c00078018b855810714b2963b-0:0-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/pci0000:00/0000:00:13.2/usb3/3-3/3-3:1.0/host3/target3:0:0/3:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"12","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"E4EF-DEA7","ID_FS_UUID_ENC":"E4EF-DEA7","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Sandisk_3.2Gen1","ID_MODEL_ENC":"\\x20Sandisk\\x203.2Gen1","ID_MODEL_ID":"55b8","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"120164320","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"pci-0000:00:13.2-usb-0:3:1.0-scsi-0:0:0:0","ID_PATH_TAG":"pci-0000_00_13_2-usb-0_3_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"USB_Sandisk_3.2Gen1_0401ecef02828dbc350de682e3717dff1676f13dbda49d095f73720475bb7d9430e0000000000000000000002087c63c00078018b855810714b2963b-0:0","ID_SERIAL_SHORT":"0401ecef02828dbc350de682e3717dff1676f13dbda49d095f73720475bb7d9430e0000000000000000000002087c63c00078018b855810714b2963b","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"USB","ID_VENDOR_ENC":"\\x20USB\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"3246","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"4220157404"} Jul 08 11:46:42 volumio sudo[17636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Jul 08 11:46:42 volumio sudo[17636]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 11:46:43 volumio sudo[17636]: pam_unix(sudo:session): session closed for user root Jul 08 11:46:43 volumio volumio[1245]: umount: /media/E4EF-DEA7: target is busy. Jul 08 11:46:43 volumio volumio[1245]: error: Failed to umount E4EF-DEA7: Error: Command failed: /usr/bin/sudo /bin/umount -f "/dev/sdc1" Jul 08 11:46:43 volumio volumio[1245]: umount: /media/E4EF-DEA7: target is busy. Jul 08 11:46:43 volumio volumio[1245]: info: MyVolumio login type: Token Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:46 volumio kernel: FAT-fs (sdc1): FAT read failed (blocknr 1027) Jul 08 11:46:48 volumio volumio[1245]: info: Scanning removed location : "USB/E4EF-DEA7" Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:48 volumio volumio[1245]: info: Jul 08 11:46:48 volumio volumio[1245]: ---------------------------- MPD announces state update: update Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:48 volumio volumio[1245]: info: MPD Database updated - AlbumList cache refreshed Jul 08 11:46:48 volumio volumio[1245]: info: Jul 08 11:46:48 volumio volumio[1245]: ---------------------------- MPD announces state update: database Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:48 volumio volumio[1245]: info: Jul 08 11:46:48 volumio volumio[1245]: ---------------------------- MPD announces state update: update Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand status took 31 milliseconds Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand status took 30 milliseconds Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:48 volumio volumio[1245]: info: Command Router : Notfying DB Updatefalse Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand status took 225 milliseconds Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand status took 225 milliseconds Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand status took 224 milliseconds Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 23 milliseconds Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:48 volumio volumio[1245]: info: Command Router : Notfying DB Updatefalse Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90428,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Swallow Your Pride","artist":"Rhys","album":"Swallow Your Pride","uri":"USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Rhys - Swallow Your Pride.flac","trackType":"flac"} Jul 08 11:46:48 volumio volumio[1245]: verbose: CURRENT POSITION 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jul 08 11:46:48 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: ------------------------------ 311ms Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 27 milliseconds Jul 08 11:46:48 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 26 milliseconds Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jul 08 11:46:48 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90451,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Swallow Your Pride","artist":"Rhys","album":"Swallow Your Pride","uri":"USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Rhys - Swallow Your Pride.flac","trackType":"flac"} Jul 08 11:46:48 volumio volumio[1245]: verbose: CURRENT POSITION 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jul 08 11:46:48 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: verbose: STATE SERVICE {"status":"play","position":0,"seek":90451,"duration":218,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"826 Kbps","isStreaming":false,"title":"Swallow Your Pride","artist":"Rhys","album":"Swallow Your Pride","uri":"USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Rhys - Swallow Your Pride.flac","trackType":"flac"} Jul 08 11:46:48 volumio volumio[1245]: verbose: CURRENT POSITION 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState stateService play Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jul 08 11:46:48 volumio volumio[1245]: info: Received an update from plugin. extracting info from payload Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:48 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:48 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:48 volumio volumio[1245]: info: ------------------------------ 315ms Jul 08 11:46:48 volumio volumio[1245]: info: ------------------------------ 315ms Jul 08 11:46:48 volumio kernel: usb usb4-port3: attempt power cycle Jul 08 11:46:51 volumio kernel: usb usb4-port3: unable to enumerate USB device Jul 08 11:46:59 volumio volumio[1245]: info: Jul 08 11:46:59 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jul 08 11:46:59 volumio volumio[1245]: info: Ignoring MPD Status Update Jul 08 11:46:59 volumio volumio[1245]: info: Jul 08 11:46:59 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jul 08 11:46:59 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:59 volumio volumio[1245]: error: updateQueue error: null Jul 08 11:46:59 volumio volumio[1245]: info: ------------------------------ 2ms Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand status took 5 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:59 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 22 Jul 08 11:46:59 volumio volumio[1245]: 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 08 11:46:59 volumio volumio[1245]: verbose: CURRENT POSITION 22 Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus play Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::play index undefined Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::startPlaybackTimer Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::clearAddPlayTracks USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Ali Farka Touré - Gomni (with Ry Coo.flac Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand stop Jul 08 11:46:59 volumio volumio[1245]: info: ------------------------------ 25ms Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand stop took 14 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clear Jul 08 11:46:59 volumio volumio[1245]: info: Jul 08 11:46:59 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jul 08 11:46:59 volumio volumio[1245]: info: Ignoring MPD Status Update Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand clear took 9 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand add "USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Ali Farka Touré - Gomni (with Ry Coo.flac" Jul 08 11:46:59 volumio volumio[1245]: error: updateQueue error: null Jul 08 11:46:59 volumio volumio[1245]: info: Jul 08 11:46:59 volumio volumio[1245]: ---------------------------- MPD announces system playlist update Jul 08 11:46:59 volumio volumio[1245]: info: Ignoring MPD Status Update Jul 08 11:46:59 volumio volumio[1245]: info: ------------------------------ 3ms Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand add "USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Ali Farka Touré - Gomni (with Ry Coo.flac" took 2 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand play Jul 08 11:46:59 volumio volumio[1245]: info: ------------------------------ 2ms Jul 08 11:46:59 volumio volumio[1245]: info: Jul 08 11:46:59 volumio volumio[1245]: ---------------------------- MPD announces state update: player Jul 08 11:46:59 volumio kernel: fat__get_entry: 1422 callbacks suppressed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034848) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034849) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034850) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034851) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034852) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034853) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034854) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034855) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034856) failed Jul 08 11:46:59 volumio kernel: FAT-fs (sdc1): Directory bread(block 7034857) failed Jul 08 11:46:59 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand status took 10 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand clearerror took 2 milliseconds Jul 08 11:46:59 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 08 11:46:59 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jul 08 11:46:59 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: 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":"Gomni (with Ry Cooder)","artist":"Ali Farka Touré","album":"Talking Timbuktu (with Ry Cooder)","uri":"USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Ali Farka Touré - Gomni (with Ry Coo.flac","trackType":"flac"} Jul 08 11:46:59 volumio volumio[1245]: verbose: CURRENT POSITION 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: No code Jul 08 11:46:59 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:46:59 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:46:59 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:46:59 volumio volumio[1245]: info: ------------------------------ 30ms Jul 08 11:47:13 volumio volumio[1245]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Jul 08 11:47:13 volumio volumio[1245]: info: Retrying Login Due to Network Error Jul 08 11:47:14 volumio kernel: usb 3-3: new high-speed USB device number 7 using ehci-pci Jul 08 11:47:14 volumio kernel: usb 3-3: New USB device found, idVendor=152d, idProduct=0578, bcdDevice= 2.15 Jul 08 11:47:14 volumio kernel: usb 3-3: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 08 11:47:14 volumio kernel: usb 3-3: Product: USB to ATA/ATAPI Bridge Jul 08 11:47:14 volumio kernel: usb 3-3: Manufacturer: JMicron Jul 08 11:47:14 volumio kernel: usb 3-3: SerialNumber: 0123456789ABCDEF Jul 08 11:47:14 volumio kernel: scsi host4: uas Jul 08 11:47:14 volumio kernel: scsi 4:0:0:0: Direct-Access JMicron Generic 0215 PQ: 0 ANSI: 6 Jul 08 11:47:14 volumio kernel: sd 4:0:0:0: Attached scsi generic sg1 type 0 Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] 976773168 512-byte logical blocks: (500 GB/466 GiB) Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] 4096-byte physical blocks Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Write Protect is off Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Mode Sense: 5f 00 00 08 Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Disabling FUA Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Preferred minimum I/O size 4096 bytes Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Optimal transfer size 33553920 bytes not a multiple of preferred minimum block size (4096 bytes) Jul 08 11:47:16 volumio kernel: sdd: sdd1 Jul 08 11:47:16 volumio kernel: sd 4:0:0:0: [sdd] Attached SCSI disk Jul 08 11:47:16 volumio volumio[1245]: info: Mounting Device 0C4211070C421107 Jul 08 11:47:16 volumio sudo[17800]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdd1 /mnt/USB/0C4211070C421107 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 08 11:47:16 volumio sudo[17800]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 08 11:47:17 volumio ntfs-3g[17803]: Version 2017.3.23AR.3 integrated FUSE 28 Jul 08 11:47:17 volumio ntfs-3g[17803]: Mounted /dev/sdd1 (Read-Write, label "", NTFS 3.1) Jul 08 11:47:17 volumio ntfs-3g[17803]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Jul 08 11:47:17 volumio ntfs-3g[17803]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdd1,blkdev,blksize=4096 Jul 08 11:47:17 volumio ntfs-3g[17803]: Global ownership and permissions enforced, configuration type 7 Jul 08 11:47:17 volumio sudo[17800]: pam_unix(sudo:session): session closed for user root Jul 08 11:47:17 volumio volumio[1245]: info: Scanning new location : "USB/0C4211070C421107" Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:47:17 volumio volumio[1245]: info: Jul 08 11:47:17 volumio volumio[1245]: ---------------------------- MPD announces state update: update Jul 08 11:47:17 volumio volumio[1245]: info: ControllerMpd::getState Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand status Jul 08 11:47:17 volumio volumio[1245]: info: sendMpdCommand status took 3 milliseconds Jul 08 11:47:17 volumio volumio[1245]: info: sendMpdCommand status took 1 milliseconds Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:47:17 volumio volumio[1245]: info: Command Router : Notfying DB Updatetrue Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::parseState Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 08 11:47:17 volumio volumio[1245]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 08 11:47:17 volumio volumio[1245]: verbose: ControllerMpd::parseTrackInfo Jul 08 11:47:17 volumio volumio[1245]: info: ControllerMpd::pushState Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::servicePushState Jul 08 11:47:17 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:47:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:47:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:47:17 volumio volumio[1245]: 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":"Gomni (with Ry Cooder)","artist":"Ali Farka Touré","album":"Talking Timbuktu (with Ry Cooder)","uri":"USB/E4EF-DEA7/FLAC (16bit-44.1kHz)/Ali Farka Touré - Gomni (with Ry Coo.flac","trackType":"flac"} Jul 08 11:47:17 volumio volumio[1245]: verbose: CURRENT POSITION 23 Jul 08 11:47:17 volumio volumio[1245]: info: CoreStateMachine::syncState stateService stop Jul 08 11:47:17 volumio volumio[1245]: info: CoreStateMachine::syncState currentStatus stop Jul 08 11:47:17 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:47:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:47:17 volumio volumio[1245]: info: No code Jul 08 11:47:17 volumio volumio[1245]: info: CoreStateMachine::pushState Jul 08 11:47:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:47:17 volumio volumio[1245]: info: CoreCommandRouter::volumioPushState Jul 08 11:47:17 volumio volumio[1245]: info: ------------------------------ 268ms Jul 08 11:47:21 volumio sudo[17445]: pam_unix(sudo:session): session closed for user root Jul 08 11:47:23 volumio volumio[1245]: info: MyVolumio login type: Token Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: info: Retrieving Cloud Streaming UI Jul 08 11:47:29 volumio volumio[1245]: info: Getting Tidal Cloud Configuration Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: info: Getting Qobuz Cloud Configuration Jul 08 11:47:29 volumio volumio[1245]: info: Asking plugin for UI Config Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: info: Getting Spotify Cloud Configuration Jul 08 11:47:29 volumio volumio[1245]: info: Asking plugin for UI Config Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: info: Saving Spotify Acccount Jul 08 11:47:29 volumio volumio[1245]: info: Got Tidal Cloud Configuration Jul 08 11:47:29 volumio volumio[1245]: info: Got it Jul 08 11:47:29 volumio volumio[1245]: info: Got it Jul 08 11:47:29 volumio volumio[1245]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 11:47:29 volumio volumio[1245]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 11:47:29 volumio volumio[1245]: info: CoreCommandRouter::volumioGetBrowseSources Jul 08 11:47:30 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 08 11:47:30 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 08 11:47:32 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jul 08 11:47:32 volumio volumio[1245]: info: CorePlayQueue::getTrack 23 Jul 08 11:47:32 volumio screensaver.sh[770]: 17840 17124 16822 Jul 08 11:47:32 volumio volumio[1245]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jul 08 11:47:32 volumio volumio[1245]: Terminated Jul 08 11:47:32 volumio volumio[1245]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jul 08 11:47:32 volumio volumio[1245]: No protocol specified Jul 08 11:47:32 volumio volumio[1245]: Exception in thread Thread-4: Jul 08 11:47:32 volumio volumio[1245]: Traceback (most recent call last): Jul 08 11:47:32 volumio volumio[1245]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jul 08 11:47:32 volumio volumio[1245]: self.run() Jul 08 11:47:32 volumio volumio[1245]: File "/usr/lib/python3.7/threading.py", line 865, in run Jul 08 11:47:32 volumio volumio[1245]: self._target(*self._args, **self._kwargs) Jul 08 11:47:32 volumio volumio[1245]: File "./screensaver/volumio_peppymeter.py", line 232, in meter_thread Jul 08 11:47:32 volumio volumio[1245]: File "./screensaver/volumio_peppymeter.py", line 281, in init_display Jul 08 11:47:32 volumio volumio[1245]: pygame.error: Unable to open a console terminal Jul 08 11:47:32 volumio volumio[1245]: Terminated Jul 08 11:47:32 volumio volumio[1245]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jul 08 11:47:32 volumio volumio[1245]: No protocol specified Jul 08 11:47:32 volumio volumio[1245]: Exception in thread Thread-4: Jul 08 11:47:32 volumio volumio[1245]: Traceback (most recent call last): Jul 08 11:47:32 volumio volumio[1245]: File "/usr/lib/python3.7/threading.py", line 917, in _bootstrap_inner Jul 08 11:47:32 volumio volumio[1245]: self.run() Jul 08 11:47:32 volumio volumio[1245]: File "/usr/lib/python3.7/threading.py", line 865, in run Jul 08 11:47:32 volumio volumio[1245]: self._target(*self._args, **self._kwargs) Jul 08 11:47:32 volumio volumio[1245]: File "./screensaver/volumio_peppymeter.py", line 232, in meter_thread Jul 08 11:47:32 volumio volumio[1245]: File "./screensaver/volumio_peppymeter.py", line 281, in init_display Jul 08 11:47:32 volumio volumio[1245]: pygame.error: Unable to open a console terminal Jul 08 11:47:32 volumio volumio[1245]: Terminated Jul 08 11:47:32 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 11:47:32 volumio volumio[1245]: Error: connect ETIMEDOUT 185.45.7.97:443 Jul 08 11:47:32 volumio volumio[1245]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Jul 08 11:47:32 volumio volumio[1245]: errno: -110, Jul 08 11:47:32 volumio volumio[1245]: code: 'ETIMEDOUT', Jul 08 11:47:32 volumio volumio[1245]: syscall: 'connect', Jul 08 11:47:32 volumio volumio[1245]: address: '185.45.7.97', Jul 08 11:47:32 volumio volumio[1245]: port: 443 Jul 08 11:47:32 volumio volumio[1245]: } Jul 08 11:47:32 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 11:47:33 volumio sudo[17861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-08 11:46 Jul 08 11:47:33 volumio sudo[17861]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"