-- Logs begin at Mon 2024-07-08 18:05:11 BST, end at Mon 2024-07-08 18:21:59 BST. -- Jul 08 18:20:08 volumio go-librespot[1179]: time="2024-07-08T18:20:08+01:00" level=debug msg="fetched chunk 4/19, size: 524288" Jul 08 18:20:20 volumio go-librespot[1179]: time="2024-07-08T18:20:20+01:00" level=debug msg="fetched chunk 5/19, size: 524288" Jul 08 18:20:32 volumio go-librespot[1179]: time="2024-07-08T18:20:32+01:00" level=debug msg="fetched chunk 6/19, size: 524288" Jul 08 18:20:42 volumio systemd[1]: Starting Cleanup of Temporary Directories... Jul 08 18:20:42 volumio systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jul 08 18:20:42 volumio systemd[1]: Started Cleanup of Temporary Directories. Jul 08 18:20:44 volumio go-librespot[1179]: time="2024-07-08T18:20:44+01:00" level=debug msg="fetched chunk 7/19, size: 524288" Jul 08 18:20:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/boot /dev/disk/by-id/mmc-SL32G_0x840c0f69-part1 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-uuid/FD90-ED9E /dev/disk/by-partuuid/078d61af-01","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"FD90-ED9E","ID_FS_UUID_ENC":"FD90-ED9E","ID_FS_VERSION":"FAT32","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"078d61af-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"1","PARTN":"1","SEQNUM":"2311","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3266600"} Jul 08 18:20:52 volumio systemd[1]: Stopped target Local File Systems. Jul 08 18:20:52 volumio kernel: mmc0: card aaaa removed Jul 08 18:20:52 volumio kernel: Aborting journal on device mmcblk0p3-8. Jul 08 18:20:52 volumio kernel: JBD2: I/O error when updating journal superblock for mmcblk0p3-8. Jul 08 18:20:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/826680f7-a407-4ab9-8bd5-1855bda526fd /dev/disk/by-id/mmc-SL32G_0x840c0f69-part2 /dev/disk/by-partuuid/078d61af-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"826680f7-a407-4ab9-8bd5-1855bda526fd","ID_FS_UUID_ENC":"826680f7-a407-4ab9-8bd5-1855bda526fd","ID_FS_VERSION":"1.0","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"078d61af-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"2","PARTN":"2","SEQNUM":"2312","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3216214"} Jul 08 18:20:52 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/078d61af-03 /dev/disk/by-id/mmc-SL32G_0x840c0f69-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3 /dev/disk/by-uuid/fecb0823-2e5b-4bba-9498-dd37b5084148","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"25","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"fecb0823-2e5b-4bba-9498-dd37b5084148","ID_FS_UUID_ENC":"fecb0823-2e5b-4bba-9498-dd37b5084148","ID_FS_VERSION":"1.0","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"078d61af-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"3","PARTN":"3","SEQNUM":"2313","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3243792"} Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:20:52 volumio kernel: Buffer I/O error on dev mmcblk0p3, logical block 1, lost sync page write Jul 08 18:20:52 volumio kernel: EXT4-fs (mmcblk0p3): I/O error while writing superblock Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3192]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3192]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio systemd[3193]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3193]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3194]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3194]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3195]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3195]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio systemd[3196]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3196]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3197]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3197]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3198]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3198]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:20:52 volumio systemd[3199]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3199]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3200]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3200]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3201]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3201]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3202]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3202]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3203]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3203]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3204]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3204]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3205]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3205]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3206]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3206]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3207]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3207]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:20:52 volumio systemd[1]: Unmounting /boot... Jul 08 18:20:52 volumio systemd[3208]: boot.mount: Failed to execute command: Input/output error Jul 08 18:20:52 volumio systemd[3208]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:20:52 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:20:52 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:20:52 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device, but not stopping since we tried this too often recently. Jul 08 18:20:52 volumio systemd[1]: systemd-fsck@dev-disk-by\x2duuid-FD90\x2dED9E.service: Succeeded. Jul 08 18:20:52 volumio systemd[1]: Stopped File System Check on /dev/disk/by-uuid/FD90-ED9E. Jul 08 18:20:52 volumio kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jul 08 18:20:52 volumio kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB Jul 08 18:20:52 volumio kernel: mmcblk0: p1 p2 p3 Jul 08 18:20:52 volumio kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB (quirks 0x00004000) Jul 08 18:20:52 volumio volumio[997]: info: Ignoring mount for partition: volumio_data Jul 08 18:20:52 volumio volumio[997]: info: Ignoring mount for partition: boot Jul 08 18:20:52 volumio volumio[997]: info: Ignoring mount for partition: volumio Jul 08 18:20:56 volumio go-librespot[1179]: time="2024-07-08T18:20:56+01:00" level=debug msg="fetched chunk 8/19, size: 524288" Jul 08 18:21:03 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/boot /dev/disk/by-id/mmc-SL32G_0x840c0f69-part1 /dev/disk/by-path/platform-fe340000.mmc-part1 /dev/disk/by-uuid/FD90-ED9E /dev/disk/by-partuuid/078d61af-01","DEVNAME":"/dev/mmcblk0p1","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p1","DEVTYPE":"partition","DISKSEQ":"27","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"FD90-ED9E","ID_FS_UUID_ENC":"FD90-ED9E","ID_FS_VERSION":"FAT32","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"1","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"187500","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"078d61af-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"33","PARTN":"1","SEQNUM":"2325","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"924873785"} Jul 08 18:21:03 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","ACTION":"remove","DEVLINKS":"/dev/disk/by-partuuid/078d61af-03 /dev/disk/by-id/mmc-SL32G_0x840c0f69-part3 /dev/disk/by-label/volumio_data /dev/disk/by-path/platform-fe340000.mmc-part3 /dev/disk/by-uuid/fecb0823-2e5b-4bba-9498-dd37b5084148","DEVNAME":"/dev/mmcblk0p3","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p3","DEVTYPE":"partition","DISKSEQ":"27","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"fecb0823-2e5b-4bba-9498-dd37b5084148","ID_FS_UUID_ENC":"fecb0823-2e5b-4bba-9498-dd37b5084148","ID_FS_VERSION":"1.0","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"56865792","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"078d61af-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"35","PARTN":"3","SEQNUM":"2327","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"924873582"} Jul 08 18:21:03 volumio volumio[997]: info: Partition removed: {"syspath":"/sys/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-fe340000.mmc-part2 /dev/disk/by-uuid/826680f7-a407-4ab9-8bd5-1855bda526fd /dev/disk/by-id/mmc-SL32G_0x840c0f69-part2 /dev/disk/by-partuuid/078d61af-02 /dev/disk/by-label/volumio","DEVNAME":"/dev/mmcblk0p2","DEVPATH":"/devices/platform/emmc2bus/fe340000.mmc/mmc_host/mmc0/mmc0:aaaa/block/mmcblk0/mmcblk0p2","DEVTYPE":"partition","DISKSEQ":"27","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"826680f7-a407-4ab9-8bd5-1855bda526fd","ID_FS_UUID_ENC":"826680f7-a407-4ab9-8bd5-1855bda526fd","ID_FS_VERSION":"1.0","ID_NAME":"SL32G","ID_PART_ENTRY_DISK":"179:32","ID_PART_ENTRY_NUMBER":"2","ID_PART_ENTRY_OFFSET":"188416","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"5279744","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"078d61af-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"078d61af","ID_PATH":"platform-fe340000.mmc","ID_PATH_TAG":"platform-fe340000_mmc","ID_SERIAL":"0x840c0f69","MAJOR":"179","MINOR":"34","PARTN":"2","SEQNUM":"2326","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"924874579"} Jul 08 18:21:03 volumio kernel: mmc0: card aaaa removed Jul 08 18:21:03 volumio kernel: EXT4-fs error: 10 callbacks suppressed Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #2549761: comm node: reading directory lblock 0 Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3217]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3217]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3218]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3218]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3219]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3219]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio systemd[3220]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3220]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3221]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3221]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3222]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3222]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3223]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3223]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio kernel: EXT4-fs error (device mmcblk0p3): __ext4_find_entry:1684: inode #5293402: comm (umount): reading directory lblock 0 Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3224]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3224]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3225]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3225]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3226]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3226]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3227]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3227]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3228]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3228]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3229]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3229]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3230]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3230]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3231]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3231]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3232]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3232]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device. Stopping, too. Jul 08 18:21:03 volumio systemd[1]: Unmounting /boot... Jul 08 18:21:03 volumio systemd[3233]: boot.mount: Failed to execute command: Input/output error Jul 08 18:21:03 volumio systemd[3233]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Jul 08 18:21:03 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Jul 08 18:21:03 volumio systemd[1]: Failed unmounting /boot. Jul 08 18:21:03 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-FD90\x2dED9E.device, but not stopping since we tried this too often recently. Jul 08 18:21:04 volumio kernel: mmc0: new ultra high speed DDR50 SDHC card at address aaaa Jul 08 18:21:04 volumio kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB Jul 08 18:21:04 volumio kernel: mmcblk0: p1 p2 p3 Jul 08 18:21:04 volumio kernel: mmcblk0: mmc0:aaaa SL32G 29.7 GiB (quirks 0x00004000) Jul 08 18:21:04 volumio volumio[997]: info: Ignoring mount for partition: volumio_data Jul 08 18:21:04 volumio volumio[997]: info: Ignoring mount for partition: volumio Jul 08 18:21:04 volumio volumio[997]: info: Ignoring mount for partition: boot Jul 08 18:21:06 volumio nmbd[3237]: [2024/07/08 18:21:06.551479, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 08 18:21:06 volumio nmbd[3237]: wins_write_database: Can't open /var/lib/samba/wins.dat.3237: Read-only file system Jul 08 18:21:06 volumio kernel: EXT4-fs (mmcblk0p3): Remounting filesystem read-only Jul 08 18:21:09 volumio go-librespot[1179]: time="2024-07-08T18:21:09+01:00" level=debug msg="fetched chunk 9/19, size: 524288" Jul 08 18:21:20 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Jul 08 18:21:20 volumio volumio[997]: info: CorePlayQueue::getTrack 1 Jul 08 18:21:21 volumio go-librespot[1179]: time="2024-07-08T18:21:21+01:00" level=debug msg="fetched chunk 10/19, size: 524288" Jul 08 18:21:28 volumio go-librespot[1179]: time="2024-07-08T18:21:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 342" Jul 08 18:21:28 volumio go-librespot[1179]: time="2024-07-08T18:21:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 342" Jul 08 18:21:28 volumio go-librespot[1179]: time="2024-07-08T18:21:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2030" Jul 08 18:21:28 volumio go-librespot[1179]: time="2024-07-08T18:21:28+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2030" Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=debug msg="put connect state inactive" Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=trace msg="emitting websocket event: inactive" Jul 08 18:21:29 volumio volumio[997]: SPOTIFY: received: {"type":"inactive","data":null} Jul 08 18:21:29 volumio volumio[997]: error: Failed to decode event: inactive Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 08 18:21:29 volumio go-librespot[1179]: time="2024-07-08T18:21:29+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 08 18:21:30 volumio nmbd[3239]: [2024/07/08 18:21:30.575677, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 08 18:21:30 volumio nmbd[3239]: wins_write_database: Can't open /var/lib/samba/wins.dat.3239: Read-only file system Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="handling transfer player command from 9f6ecc82995593fad30ca0a5ede937888b37cbdf" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="fetched new page 0 with 33 items (list: 33)" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="loading track spotify:track:0DV4IzvzOTcROoEp9Gwt4X (paused: false, position: 2740ms)" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="emitting websocket event: will_play" Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","play_origin":"com.spotify.service.freetierartist"}} Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:0DV4IzvzOTcROoEp9Gwt4X" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="requested aes key for file bcc3fb987e83d90986501ee342d76f3b82c5c1d1, gid: 0DV4IzvzOTcROoEp9Gwt4X" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="fetched first chunk of 21, total size is 10607800 bytes" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="seek to 2740ms (diff: 214ms, samples: 120834, bytes: 89216)" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=info msg="loaded track \"Blue Denim Jeans - Nia Archives Remix\" (uri: spotify:track:0DV4IzvzOTcROoEp9Gwt4X, paused: false, position: 2740ms, duration: 295373ms)" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="emitting websocket event: metadata" Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","name":"Blue Denim Jeans - Nia Archives Remix","artist_names":["p-rallel","Lauren Faith","Nia Archives"],"album_name":"Blue Denim Jeans (Nia Archives Remix)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02725b72f1003cf2360cbc7f2e","position":2740,"duration":295373,"release_date":"year:2021 month:4 day:21","track_number":1,"disc_number":1}} Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="emitting websocket event: active" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="sending successful reply for delaer request" Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: received: {"type":"active","data":null} Jul 08 18:21:31 volumio volumio[997]: info: Aligning Spotify Volume to Volumio Volume Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Jul 08 18:21:31 volumio volumio[997]: info: CorePlayQueue::getTrack 1 Jul 08 18:21:31 volumio volumio[997]: info: Setting Spotify Volume from Volumio: 100 Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1959" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="fetched chunk 1/20, size: 524288" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 08 18:21:31 volumio go-librespot[1179]: time="2024-07-08T18:21:31+01:00" level=trace msg="emitting websocket event: playing" Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","play_origin":"com.spotify.service.freetierartist"}} Jul 08 18:21:31 volumio volumio[997]: info: Spotify is playing in volatile mode Jul 08 18:21:31 volumio volumio[997]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: UNSET VOLATILE Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: {"status":"play","position":1,"title":"Still Think About You","artist":"A Boogie Wit da Hoodie","album":"Artist","albumart":"https://i.scdn.co/image/ab67616d0000b273f2baf6d743ae26f97777f026","uri":"spotify:track:2FTeQQ5Rg9tBpgpTF5mnhE","trackType":"spotify","codec":"ogg","seek":0,"duration":246,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":"100","dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 08 18:21:31 volumio volumio[997]: info: Setting Spotify stop after unset volatile call Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: {"status":"play","service":"spop","title":"Blue Denim Jeans - Nia Archives Remix","artist":"p-rallel, Lauren Faith, Nia Archives","album":"Blue Denim Jeans (Nia Archives Remix)","albumart":"https://i.scdn.co/image/ab67616d00001e02725b72f1003cf2360cbc7f2e","uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","trackType":"spotify","seek":2740,"duration":295,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::servicePushState Jul 08 18:21:31 volumio volumio[997]: info: CoreStateMachine::pushState Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Jul 08 18:21:31 volumio volumio[997]: info: [LastFM] Current track has sufficient metadata: title (Blue Denim Jeans - Nia Archives Remix) and artist (p-rallel, Lauren Faith, Nia Archives) passed on explicitly Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: PUSH STATE SPOTIFY Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: {"status":"play","service":"spop","title":"Blue Denim Jeans - Nia Archives Remix","artist":"p-rallel, Lauren Faith, Nia Archives","album":"Blue Denim Jeans (Nia Archives Remix)","albumart":"https://i.scdn.co/image/ab67616d00001e02725b72f1003cf2360cbc7f2e","uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","trackType":"spotify","seek":2740,"duration":295,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::servicePushState Jul 08 18:21:31 volumio volumio[997]: info: CoreStateMachine::pushState Jul 08 18:21:31 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Jul 08 18:21:31 volumio volumio[997]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 08 18:21:32 volumio go-librespot[1179]: time="2024-07-08T18:21:32+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 08 18:21:32 volumio go-librespot[1179]: time="2024-07-08T18:21:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1554" Jul 08 18:21:32 volumio volumio[997]: info: [LastFM] track info: {"success":true,"trackInfo":{"name":"Blue Denim Jeans - Nia Archives Remix","url":"https://www.last.fm/music/p-rallel,+Lauren+Faith,+Nia+Archives/_/Blue+Denim+Jeans+-+Nia+Archives+Remix","duration":"0","streamable":{"#":"0","@":{"fulltrack":"0"}},"listeners":"33","playcount":"96","artist":{"name":"p-rallel, Lauren Faith, Nia Archives","url":"https://www.last.fm/music/p-rallel,+Lauren+Faith,+Nia+Archives"},"userplaycount":"0","userloved":"0","toptags":""}} Jul 08 18:21:32 volumio volumio[997]: info: Spotify Stop Jul 08 18:21:32 volumio volumio[997]: SPOTIFY: SPOTIFY STOP Jul 08 18:21:32 volumio volumio[997]: SPOTIFY: {"status":"play","title":"Blue Denim Jeans - Nia Archives Remix","artist":"p-rallel, Lauren Faith, Nia Archives","album":"Blue Denim Jeans (Nia Archives Remix)","albumart":"https://i.scdn.co/image/ab67616d00001e02725b72f1003cf2360cbc7f2e","uri":"spotify:track:0DV4IzvzOTcROoEp9Gwt4X","trackType":"spotify","codec":"ogg","seek":2740,"duration":295,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":"100","dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jul 08 18:21:32 volumio go-librespot[1179]: time="2024-07-08T18:21:32+01:00" level=debug msg="fetched chunk 3/20, size: 524288" Jul 08 18:21:32 volumio go-librespot[1179]: time="2024-07-08T18:21:32+01:00" level=debug msg="fetched chunk 2/20, size: 524288" Jul 08 18:21:33 volumio volumio[997]: SPOTIFY: SETTING SPOTIFY VOLUME 100 Jul 08 18:21:33 volumio volumio[997]: info: Sending Spotify command with payload to local API: /player/volume Jul 08 18:21:33 volumio volumio[997]: error: Failed to send command to Spotify local API: /player/volume: Error: Internal Server Error Jul 08 18:21:41 volumio volumio[997]: info: VolumeController::SetAlsaVolume+ Jul 08 18:21:41 volumio volumio[997]: info: CoreStateMachine::pushState Jul 08 18:21:41 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 08 18:21:41 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: SPOTIFY VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: VOLUMIO VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 08 18:21:41 volumio go-librespot[1179]: time="2024-07-08T18:21:41+01:00" level=debug msg="fetched chunk 4/20, size: 524288" Jul 08 18:21:41 volumio volumio[997]: info: VolumeController::SetAlsaVolume+ Jul 08 18:21:41 volumio volumio[997]: info: CoreStateMachine::pushState Jul 08 18:21:41 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: SPOTIFY VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: VOLUMIO VOLUME 100 Jul 08 18:21:41 volumio volumio[997]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 08 18:21:42 volumio volumio[997]: info: VolumeController::SetAlsaVolume+ Jul 08 18:21:42 volumio volumio[997]: info: CoreStateMachine::pushState Jul 08 18:21:42 volumio volumio[997]: info: CoreCommandRouter::volumioPushState Jul 08 18:21:42 volumio volumio[997]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 08 18:21:42 volumio volumio[997]: SPOTIFY: SPOTIFY VOLUME 100 Jul 08 18:21:42 volumio volumio[997]: SPOTIFY: VOLUMIO VOLUME 100 Jul 08 18:21:42 volumio volumio[997]: SPOTIFY: DELTA VOLUME ENOUGH: false Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 08 18:21:49 volumio volumio[997]: info: Getting Alsa Cards List without I2S DAC Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 08 18:21:49 volumio volumio[997]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 08 18:21:54 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Jul 08 18:21:55 volumio go-librespot[1179]: time="2024-07-08T18:21:55+01:00" level=debug msg="fetched chunk 5/20, size: 524288" Jul 08 18:21:59 volumio volumio[997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 18:21:59 volumio kernel: EXT4-fs warning (device mmcblk0p3): htree_dirblock_to_tree:1082: inode #5290852: lblock 0: comm node: error -5 reading directory block Jul 08 18:21:59 volumio volumio[997]: Error: EIO: i/o error, scandir '/data/plugins/' Jul 08 18:21:59 volumio volumio[997]: at Object.readdirSync (fs.js:1021:3) Jul 08 18:21:59 volumio volumio[997]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Jul 08 18:21:59 volumio volumio[997]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Jul 08 18:21:59 volumio volumio[997]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1321:45) Jul 08 18:21:59 volumio volumio[997]: at Socket.emit (events.js:315:20) Jul 08 18:21:59 volumio volumio[997]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 08 18:21:59 volumio volumio[997]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 08 18:21:59 volumio volumio[997]: errno: -5, Jul 08 18:21:59 volumio volumio[997]: syscall: 'scandir', Jul 08 18:21:59 volumio volumio[997]: code: 'EIO', Jul 08 18:21:59 volumio volumio[997]: path: '/data/plugins/' Jul 08 18:21:59 volumio volumio[997]: } Jul 08 18:21:59 volumio volumio[997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 08 18:21:59 volumio sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-08 18:20 Jul 08 18:21:59 volumio sudo[3313]: 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"