-- Logs begin at Mon 2025-02-17 08:54:04 WIB, end at Tue 2025-02-18 03:20:48 WIB. -- Feb 18 03:19:53 volumio-living-room volumiologrotate[687]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 18 03:19:53 volumio-living-room volumiologrotate[687]: ls: cannot access 'LIVING': No such file or directory Feb 18 03:19:53 volumio-living-room volumiologrotate[687]: ls: cannot access 'ROOM': No such file or directory Feb 18 03:20:36 volumio-living-room go-librespot[10335]: time="2025-02-18T03:20:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed initializing accesspoint: failed dialing accesspoint: dial tcp 104.199.65.124:4070: connect: connection timed out" Feb 18 03:20:36 volumio-living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 18 03:20:36 volumio-living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 18 03:20:36 volumio-living-room volumio[1217]: (node:1217) UnhandledPromiseRejectionWarning: Error: socket hang up Feb 18 03:20:36 volumio-living-room volumio[1217]: at connResetException (internal/errors.js:607:14) Feb 18 03:20:36 volumio-living-room volumio[1217]: at Socket.socketOnEnd (_http_client.js:493:23) Feb 18 03:20:36 volumio-living-room volumio[1217]: at Socket.emit (events.js:327:22) Feb 18 03:20:36 volumio-living-room volumio[1217]: at endReadableNT (internal/streams/readable.js:1327:12) Feb 18 03:20:36 volumio-living-room volumio[1217]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Feb 18 03:20:36 volumio-living-room volumio[1217]: (node:1217) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 1950) Feb 18 03:20:36 volumio-living-room volumio[1217]: info: Connection to go-librespot Websocket closed Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: USB disconnect, device number 83 Feb 18 03:20:37 volumio-living-room volumio[1217]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/9EEC-AF36 /dev/disk/by-partuuid/5c1d6970-01 /dev/disk/by-id/usb-SanDisk_Cruzer_Blade_4C530001100322103415-0:0-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"409","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9EEC-AF36","ID_FS_UUID_ENC":"9EEC-AF36","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Blade","ID_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5567","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"8192","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30586880","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"5c1d6970-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"5c1d6970","ID_PATH":"platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Cruzer_Blade_4C530001100322103415-0:0","ID_SERIAL_SHORT":"4C530001100322103415","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"15186","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"173728257107"} Feb 18 03:20:37 volumio-living-room sudo[10446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Feb 18 03:20:37 volumio-living-room sudo[10446]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 03:20:37 volumio-living-room sudo[10446]: pam_unix(sudo:session): session closed for user root Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: new high-speed USB device number 84 using dwc_otg Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: Product: Cruzer Blade Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: Manufacturer: SanDisk Feb 18 03:20:37 volumio-living-room kernel: usb 1-1.4: SerialNumber: 4C530001100322103415 Feb 18 03:20:37 volumio-living-room kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Feb 18 03:20:37 volumio-living-room kernel: scsi host1: usb-storage 1-1.4:1.0 Feb 18 03:20:38 volumio-living-room kernel: scsi 1:0:0:0: Direct-Access SanDisk Cruzer Blade 1.00 PQ: 0 ANSI: 6 Feb 18 03:20:38 volumio-living-room kernel: sd 1:0:0:0: Attached scsi generic sg1 type 0 Feb 18 03:20:38 volumio-living-room kernel: sd 1:0:0:0: [sdc] 30595072 512-byte logical blocks: (15.7 GB/14.6 GiB) Feb 18 03:20:38 volumio-living-room kernel: sd 1:0:0:0: [sdc] Write Protect is off Feb 18 03:20:38 volumio-living-room kernel: sd 1:0:0:0: [sdc] Mode Sense: 43 00 00 00 Feb 18 03:20:38 volumio-living-room kernel: sd 1:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Feb 18 03:20:39 volumio-living-room kernel: usb 1-1.4: reset high-speed USB device number 84 using dwc_otg Feb 18 03:20:39 volumio-living-room kernel: sdc: sdc1 Feb 18 03:20:39 volumio-living-room kernel: sd 1:0:0:0: [sdc] Attached SCSI removable disk Feb 18 03:20:39 volumio-living-room volumio[1217]: info: Mounting Device 9EEC-AF36 Feb 18 03:20:39 volumio-living-room sudo[10458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/9EEC-AF36 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 18 03:20:39 volumio-living-room sudo[10458]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 03:20:40 volumio-living-room systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 18 03:20:40 volumio-living-room systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1401. Feb 18 03:20:40 volumio-living-room systemd[1]: Stopped go-librespot Daemon. Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 18 03:20:40 volumio-living-room sudo[10458]: pam_unix(sudo:session): session closed for user root Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Initializing connection to go-librespot Websocket Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 18 03:20:40 volumio-living-room systemd[1]: Started go-librespot Daemon. Feb 18 03:20:40 volumio-living-room go-librespot[10460]: Librespot-go daemon starting... Feb 18 03:20:40 volumio-living-room kernel: usb 1-1.4: USB disconnect, device number 84 Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8192) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8193) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8194) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8195) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8196) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8197) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8198) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8199) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8200) failed Feb 18 03:20:40 volumio-living-room kernel: FAT-fs (sdc1): Directory bread(block 8201) failed Feb 18 03:20:40 volumio-living-room go-librespot[10460]: time="2025-02-18T03:20:40+07:00" level=info msg="generated new device id: b22a1082306758ab2cabff3ee547995e32148117" Feb 18 03:20:40 volumio-living-room go-librespot[10460]: time="2025-02-18T03:20:40+07:00" level=debug msg="stored credentials found for 48abx84xgjm1uboue3ngkkna1" Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Scanning new location : "USB/9EEC-AF36" Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdc/sdc1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/9EEC-AF36 /dev/disk/by-partuuid/5c1d6970-01 /dev/disk/by-id/usb-SanDisk_Cruzer_Blade_4C530001100322103415-0:0-part1","DEVNAME":"/dev/sdc1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.0/host1/target1:0:0/1:0:0:0/block/sdc/sdc1","DEVTYPE":"partition","DISKSEQ":"410","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"9EEC-AF36","ID_FS_UUID_ENC":"9EEC-AF36","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Blade","ID_MODEL_ENC":"Cruzer\\x20Blade\\x20\\x20\\x20\\x20","ID_MODEL_ID":"5567","ID_PART_ENTRY_DISK":"8:32","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"8192","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"30586880","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"5c1d6970-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"5c1d6970","ID_PATH":"platform-3f980000.usb-usb-0:1.4:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_4_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Cruzer_Blade_4C530001100322103415-0:0","ID_SERIAL_SHORT":"4C530001100322103415","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"33","PARTN":"1","SEQNUM":"15220","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"173952873703"} Feb 18 03:20:40 volumio-living-room sudo[10471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdc1 Feb 18 03:20:40 volumio-living-room sudo[10471]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 03:20:40 volumio-living-room sudo[10471]: pam_unix(sudo:session): session closed for user root Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 27 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 26 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 11 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 9 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 15 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: info: sendMpdCommand status took 14 milliseconds Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Command Router : Notfying DB Updatetrue Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::Close All Modals sent Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Command Router : Notfying DB Updatetrue Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::Close All Modals sent Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Command Router : Notfying DB Updatetrue Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::Close All Modals sent Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:40 volumio-living-room kernel: usb 1-1.5: USB disconnect, device number 82 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: 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} Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: 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} Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: 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} Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ------------------------------ 343ms Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ------------------------------ 327ms Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ------------------------------ 326ms Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Partition removed: {"syspath":"/sys/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host2/target2:0:0/2:0:0:0/block/sdd/sdd1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0-part1 /dev/disk/by-id/usb-SanDisk_Cruzer_Force_4C530102010416105121-0:0-part1 /dev/disk/by-uuid/963A-7D11","DEVNAME":"/dev/sdd1","DEVPATH":"/devices/platform/soc/3f980000.usb/usb1/1-1/1-1.5/1-1.5:1.0/host2/target2:0:0/2:0:0:0/block/sdd/sdd1","DEVTYPE":"partition","DISKSEQ":"408","ID_BUS":"usb","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"963A-7D11","ID_FS_UUID_ENC":"963A-7D11","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"Cruzer_Force","ID_MODEL_ENC":"Cruzer\\x20Force\\x20\\x20\\x20\\x20","ID_MODEL_ID":"557d","ID_PART_ENTRY_DISK":"8:48","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"32","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"31260640","ID_PART_ENTRY_TYPE":"0xc","ID_PART_TABLE_TYPE":"dos","ID_PATH":"platform-3f980000.usb-usb-0:1.5:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-3f980000_usb-usb-0_1_5_1_0-scsi-0_0_0_0","ID_REVISION":"1.00","ID_SERIAL":"SanDisk_Cruzer_Force_4C530102010416105121-0:0","ID_SERIAL_SHORT":"4C530102010416105121","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"SanDisk","ID_VENDOR_ENC":"SanDisk\\x20","ID_VENDOR_ID":"0781","MAJOR":"8","MINOR":"49","PARTN":"1","SEQNUM":"15237","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"172935410736"} Feb 18 03:20:40 volumio-living-room sudo[10481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sdd1 Feb 18 03:20:40 volumio-living-room sudo[10481]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 03:20:40 volumio-living-room systemd[1]: media-963A\x2d7D11.mount: Succeeded. Feb 18 03:20:40 volumio-living-room go-librespot[10460]: time="2025-02-18T03:20:40+07:00" level=debug msg="obtained new client token: AADXjdwmg4d+ILDyaWpUPwyTy+HZFqu5m2xcDFs1Ziub1U9YEU5x9H6dvfRdOme1uo2H6Lti35WyrbGeHZc10ozmhzvgPkJXN1ySQtZo5g0isIqG5rSL04hC0lbsiZD9KXhmadBHg3a4psiqMFOQWSZ7DfYihmgRrV7iOW7LROcaP8Lntku+vvHBGwkuFKTPW2gqjxoSmc337zBIED72l6OYUukUVCLR+WRW6LR0GuUnvNCJ6v2DsejwLuKl/Rg=" Feb 18 03:20:40 volumio-living-room sudo[10481]: pam_unix(sudo:session): session closed for user root Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:40 volumio-living-room volumio[1217]: info: MPD Database updated - AlbumList cache refreshed Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: database Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: MPD Database updated - AlbumList cache refreshed Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: database Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: MPD Database updated - AlbumList cache refreshed Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: database Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: Feb 18 03:20:40 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:40 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:40 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:41 volumio-living-room go-librespot[10460]: time="2025-02-18T03:20:41+07:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 18 03:20:41 volumio-living-room volumio[1217]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/9EEC-AF36 Feb 18 03:20:41 volumio-living-room kernel: usb 1-1.3: reset high-speed USB device number 80 using dwc_otg Feb 18 03:20:41 volumio-living-room kernel: usb 1-1.4: new high-speed USB device number 85 using dwc_otg Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: Product: Cruzer Blade Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: Manufacturer: SanDisk Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: SerialNumber: 4C530001100322103415 Feb 18 03:20:42 volumio-living-room kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Feb 18 03:20:42 volumio-living-room kernel: scsi host1: usb-storage 1-1.4:1.0 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: new high-speed USB device number 86 using dwc_otg Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: New USB device found, idVendor=0781, idProduct=557d, bcdDevice= 1.00 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: Product: Cruzer Force Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: Manufacturer: SanDisk Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.5: SerialNumber: 4C530102010416105121 Feb 18 03:20:42 volumio-living-room kernel: usb-storage 1-1.5:1.0: USB Mass Storage device detected Feb 18 03:20:42 volumio-living-room kernel: scsi host2: usb-storage 1-1.5:1.0 Feb 18 03:20:42 volumio-living-room kernel: usb 1-1.4: USB disconnect, device number 85 Feb 18 03:20:42 volumio-living-room volumio[1217]: info: Scanning removed location : "USB/9EEC-AF36" Feb 18 03:20:42 volumio-living-room kernel: fat__get_entry: 54 callbacks suppressed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8192) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8193) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8194) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8195) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8196) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8197) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8198) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8199) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8200) failed Feb 18 03:20:42 volumio-living-room kernel: FAT-fs (sdb1): Directory bread(block 8201) failed Feb 18 03:20:42 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:42 volumio-living-room volumio[1217]: info: Feb 18 03:20:42 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:42 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:42 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:42 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:42 volumio-living-room volumio[1217]: info: Feb 18 03:20:42 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:42 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:42 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:43 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:43 volumio-living-room volumio[1217]: info: Feb 18 03:20:43 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:43 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:43 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:43 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:43 volumio-living-room volumio[1217]: info: Feb 18 03:20:43 volumio-living-room volumio[1217]: ---------------------------- MPD announces state update: update Feb 18 03:20:43 volumio-living-room volumio[1217]: info: ControllerMpd::getState Feb 18 03:20:43 volumio-living-room volumio[1217]: verbose: ControllerMpd::sendMpdCommand status Feb 18 03:20:43 volumio-living-room volumio[1217]: info: Initializing connection to go-librespot Websocket Feb 18 03:20:43 volumio-living-room kernel: scsi 2:0:0:0: Direct-Access SanDisk Cruzer Force 1.00 PQ: 0 ANSI: 6 Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: [sdc] 31260672 512-byte logical blocks: (16.0 GB/14.9 GiB) Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: [sdc] Write Protect is off Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: [sdc] Mode Sense: 43 00 00 00 Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Feb 18 03:20:43 volumio-living-room kernel: sdc: sdc1 Feb 18 03:20:43 volumio-living-room kernel: sd 2:0:0:0: [sdc] Attached SCSI removable disk Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: new high-speed USB device number 87 using dwc_otg Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: New USB device found, idVendor=0781, idProduct=5567, bcdDevice= 1.00 Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: Product: Cruzer Blade Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: Manufacturer: SanDisk Feb 18 03:20:44 volumio-living-room kernel: usb 1-1.4: SerialNumber: 4C530001100322103415 Feb 18 03:20:44 volumio-living-room kernel: usb-storage 1-1.4:1.0: USB Mass Storage device detected Feb 18 03:20:44 volumio-living-room kernel: scsi host1: usb-storage 1-1.4:1.0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: sendMpdCommand status took 3607 milliseconds Feb 18 03:20:44 volumio-living-room volumio[1217]: info: sendMpdCommand status took 3606 milliseconds Feb 18 03:20:44 volumio-living-room volumio[1217]: info: sendMpdCommand status took 3605 milliseconds Feb 18 03:20:44 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: Command Router : Notfying DB Updatefalse Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::Close All Modals sent Feb 18 03:20:44 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:44 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: 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} Feb 18 03:20:44 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: 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} Feb 18 03:20:44 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:44 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:44 volumio-living-room volumio[1217]: info: ------------------------------ 3708ms Feb 18 03:20:44 volumio-living-room volumio[1217]: info: ------------------------------ 3710ms Feb 18 03:20:44 volumio-living-room volumio[1217]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/9EEC-AF36 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room volumio[1217]: SPOTIFY: RECEIVED VOLUMIO VOLUME 10 Feb 18 03:20:44 volumio-living-room go-librespot[10460]: time="2025-02-18T03:20:44+07:00" level=debug msg="new websocket client" Feb 18 03:20:44 volumio-living-room volumio[1217]: info: Mounting Device 963A-7D11 Feb 18 03:20:44 volumio-living-room sudo[10502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/963A-7D11 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Feb 18 03:20:44 volumio-living-room sudo[10502]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 03:20:44 volumio-living-room kernel: FAT-fs (sdc1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Feb 18 03:20:45 volumio-living-room sudo[10502]: pam_unix(sudo:session): session closed for user root Feb 18 03:20:45 volumio-living-room kernel: FAT-fs (sdc1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Feb 18 03:20:45 volumio-living-room volumio[1217]: info: TEST: Here we shall have deleted mounted folder: /mnt/USB/963A-7D11 Feb 18 03:20:45 volumio-living-room volumio[1217]: info: Connection to go-librespot Websocket established Feb 18 03:20:45 volumio-living-room kernel: scsi 1:0:0:0: Direct-Access SanDisk Cruzer Blade 1.00 PQ: 0 ANSI: 6 Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: Attached scsi generic sg2 type 0 Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: [sdd] 30595072 512-byte logical blocks: (15.7 GB/14.6 GiB) Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: [sdd] Write Protect is off Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: [sdd] Mode Sense: 43 00 00 00 Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: [sdd] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Feb 18 03:20:45 volumio-living-room kernel: sdd: sdd1 Feb 18 03:20:45 volumio-living-room kernel: sd 1:0:0:0: [sdd] Attached SCSI removable disk Feb 18 03:20:45 volumio-living-room kernel: Feb 18 03:20:45 volumio-living-room kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1 Feb 18 03:20:45 volumio-living-room kernel: Feb 18 03:20:45 volumio-living-room kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6 Feb 18 03:20:46 volumio-living-room kernel: usb 1-1.4: reset high-speed USB device number 87 using dwc_otg Feb 18 03:20:46 volumio-living-room kernel: sd 1:0:0:0: [sdd] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Feb 18 03:20:46 volumio-living-room kernel: sd 1:0:0:0: [sdd] tag#0 CDB: opcode=0x28 28 00 00 00 03 08 00 00 f0 00 Feb 18 03:20:46 volumio-living-room kernel: I/O error, dev sdd, sector 776 op 0x0:(READ) flags 0x84700 phys_seg 16 prio class 2 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: sendMpdCommand status took 6116 milliseconds Feb 18 03:20:47 volumio-living-room volumio[1217]: info: sendMpdCommand status took 6116 milliseconds Feb 18 03:20:47 volumio-living-room volumio[1217]: info: sendMpdCommand status took 6115 milliseconds Feb 18 03:20:47 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: Command Router : Notfying DB Updatefalse Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::Close All Modals sent Feb 18 03:20:47 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:47 volumio-living-room volumio[1217]: verbose: ControllerMpd::parseState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: 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} Feb 18 03:20:47 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: ControllerMpd::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::servicePushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: 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} Feb 18 03:20:47 volumio-living-room volumio[1217]: verbose: CURRENT POSITION 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState stateService stop Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::syncState currentStatus stop Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: No code Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreStateMachine::pushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CorePlayQueue::getTrack 0 Feb 18 03:20:47 volumio-living-room volumio[1217]: info: CoreCommandRouter::volumioPushState Feb 18 03:20:47 volumio-living-room volumio[1217]: info: ------------------------------ 6219ms Feb 18 03:20:47 volumio-living-room volumio[1217]: info: ------------------------------ 6220ms Feb 18 03:20:47 volumio-living-room volumio[1217]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 03:20:47 volumio-living-room volumio[1217]: TypeError: Cannot read property 'substring' of undefined Feb 18 03:20:47 volumio-living-room volumio[1217]: at /volumio/app/plugins/music_service/mpd/index.js:2826:70 Feb 18 03:20:47 volumio-living-room volumio[1217]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Feb 18 03:20:47 volumio-living-room volumio[1217]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Feb 18 03:20:47 volumio-living-room volumio[1217]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 18 03:20:47 volumio-living-room volumio[1217]: at Socket.emit (events.js:315:20) Feb 18 03:20:47 volumio-living-room volumio[1217]: at addChunk (internal/streams/readable.js:309:12) Feb 18 03:20:47 volumio-living-room volumio[1217]: at readableAddChunk (internal/streams/readable.js:280:11) Feb 18 03:20:47 volumio-living-room volumio[1217]: at Socket.Readable.push (internal/streams/readable.js:223:10) Feb 18 03:20:47 volumio-living-room volumio[1217]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Feb 18 03:20:47 volumio-living-room volumio[1217]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 03:20:48 volumio-living-room sudo[10515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 03:19 Feb 18 03:20:48 volumio-living-room sudo[10515]: 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"