-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Fri 2025-12-26 12:32:34 JST. -- Dec 26 12:31:02 volumio volumio-remote-updater[670]: [2025-12-26 12:31:02] [connect] Successful connection Dec 26 12:31:10 volumio ntpd[854]: Soliciting pool server 2400:8902::2000:52ff:fee4:69d9 Dec 26 12:31:13 volumio ntpd[854]: Soliciting pool server 172.233.91.137 Dec 26 12:31:15 volumio ntpd[854]: Soliciting pool server 172.104.124.149 Dec 26 12:31:16 volumio ntpd[854]: Soliciting pool server 129.250.35.251 Dec 26 12:31:17 volumio ntpd[854]: Soliciting pool server 139.162.81.45 Dec 26 12:31:17 volumio volumio-remote-updater[670]: [2025-12-26 12:31:17] [connect] Successful connection Dec 26 12:31:29 volumio kernel: INFO: task jbd2/sda3-8:282 blocked for more than 122 seconds. Dec 26 12:31:29 volumio kernel: Tainted: G C 6.1.69-v7l+ #1710 Dec 26 12:31:29 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Dec 26 12:31:29 volumio kernel: task:jbd2/sda3-8 state:D stack:0 pid:282 ppid:2 flags:0x00000000 Dec 26 12:31:29 volumio kernel: __schedule from schedule+0x58/0xf0 Dec 26 12:31:29 volumio kernel: schedule from io_schedule+0x1c/0x30 Dec 26 12:31:29 volumio kernel: io_schedule from bit_wait_io+0x14/0x60 Dec 26 12:31:29 volumio kernel: bit_wait_io from __wait_on_bit+0x68/0xa4 Dec 26 12:31:29 volumio kernel: __wait_on_bit from out_of_line_wait_on_bit+0x80/0x9c Dec 26 12:31:29 volumio kernel: out_of_line_wait_on_bit from jbd2_journal_commit_transaction+0x1038/0x19b4 Dec 26 12:31:29 volumio kernel: jbd2_journal_commit_transaction from kjournald2+0xd4/0x278 Dec 26 12:31:29 volumio kernel: kjournald2 from kthread+0xe4/0x100 Dec 26 12:31:29 volumio kernel: kthread from ret_from_fork+0x14/0x30 Dec 26 12:31:29 volumio kernel: Exception stack(0xf0a09fb0 to 0xf0a09ff8) Dec 26 12:31:29 volumio kernel: 9fa0: 00000000 00000000 00000000 00000000 Dec 26 12:31:29 volumio kernel: 9fc0: 00000000 00000000 00000000 00000000 00000000 00000000 00000000 00000000 Dec 26 12:31:29 volumio kernel: 9fe0: 00000000 00000000 00000000 00000000 00000013 00000000 Dec 26 12:31:29 volumio kernel: INFO: task nmbd:793 blocked for more than 122 seconds. Dec 26 12:31:29 volumio kernel: Tainted: G C 6.1.69-v7l+ #1710 Dec 26 12:31:29 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Dec 26 12:31:29 volumio kernel: task:nmbd state:D stack:0 pid:793 ppid:1 flags:0x00000000 Dec 26 12:31:29 volumio kernel: __schedule from schedule+0x58/0xf0 Dec 26 12:31:29 volumio kernel: schedule from io_schedule+0x1c/0x30 Dec 26 12:31:29 volumio kernel: io_schedule from folio_wait_bit+0x118/0x208 Dec 26 12:31:29 volumio kernel: folio_wait_bit from folio_wait_writeback+0x40/0x80 Dec 26 12:31:29 volumio kernel: folio_wait_writeback from __filemap_fdatawait_range+0x9c/0x100 Dec 26 12:31:29 volumio kernel: __filemap_fdatawait_range from file_write_and_wait_range+0xc8/0xe0 Dec 26 12:31:29 volumio kernel: file_write_and_wait_range from ext4_sync_file+0xec/0x378 Dec 26 12:31:29 volumio kernel: ext4_sync_file from ovl_fsync+0xe0/0xf4 [overlay] Dec 26 12:31:29 volumio kernel: ovl_fsync [overlay] from do_fsync+0x44/0x78 Dec 26 12:31:29 volumio kernel: do_fsync from ret_fast_syscall+0x0/0x1c Dec 26 12:31:29 volumio kernel: Exception stack(0xf0a2dfa8 to 0xf0a2dff0) Dec 26 12:31:29 volumio kernel: dfa0: 00000000 0000d000 00000019 0000d000 0000006c 00000000 Dec 26 12:31:29 volumio kernel: dfc0: 00000000 0000d000 01351e00 00000094 00000000 01362660 b6abed2c b6aa4a34 Dec 26 12:31:29 volumio kernel: dfe0: b6abefc4 bef95620 b6aa782c b69be294 Dec 26 12:31:29 volumio kernel: INFO: task nmbd:1190 blocked for more than 122 seconds. Dec 26 12:31:29 volumio kernel: Tainted: G C 6.1.69-v7l+ #1710 Dec 26 12:31:29 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Dec 26 12:31:29 volumio kernel: task:nmbd state:D stack:0 pid:1190 ppid:793 flags:0x00000000 Dec 26 12:31:29 volumio kernel: __schedule from schedule+0x58/0xf0 Dec 26 12:31:29 volumio kernel: schedule from rwsem_down_write_slowpath+0x444/0x688 Dec 26 12:31:29 volumio kernel: rwsem_down_write_slowpath from do_unlinkat+0x10c/0x2b8 Dec 26 12:31:29 volumio kernel: do_unlinkat from ret_fast_syscall+0x0/0x1c Dec 26 12:31:29 volumio kernel: Exception stack(0xf0bd9fa8 to 0xf0bd9ff0) Dec 26 12:31:29 volumio kernel: 9fa0: b6870e0c 01349300 bef956ee 0133ce80 d019dbaa af540087 Dec 26 12:31:29 volumio kernel: 9fc0: b6870e0c 01349300 00000015 0000000a bef956c0 bef956ec 00000005 bef9575c Dec 26 12:31:29 volumio kernel: 9fe0: b6870e80 bef956a4 b685d808 b69b58ac Dec 26 12:31:29 volumio kernel: INFO: task winbindd:1191 blocked for more than 122 seconds. Dec 26 12:31:29 volumio kernel: Tainted: G C 6.1.69-v7l+ #1710 Dec 26 12:31:29 volumio kernel: "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. Dec 26 12:31:29 volumio kernel: task:winbindd state:D stack:0 pid:1191 ppid:1131 flags:0x00000000 Dec 26 12:31:29 volumio kernel: __schedule from schedule+0x58/0xf0 Dec 26 12:31:29 volumio kernel: schedule from rwsem_down_write_slowpath+0x444/0x688 Dec 26 12:31:29 volumio kernel: rwsem_down_write_slowpath from do_unlinkat+0x10c/0x2b8 Dec 26 12:31:29 volumio kernel: do_unlinkat from ret_fast_syscall+0x0/0x1c Dec 26 12:31:29 volumio kernel: Exception stack(0xf0cbdfa8 to 0xf0cbdff0) Dec 26 12:31:29 volumio kernel: dfa0: b5cf5e0c 0195c958 befff846 0194ffe8 d2c7baad a412935e Dec 26 12:31:29 volumio kernel: dfc0: b5cf5e0c 0195c958 00000015 0000000a befff818 befff844 00000005 befff8b4 Dec 26 12:31:29 volumio kernel: dfe0: b5cf5e80 befff7fc b5ce2808 b5df78ac Dec 26 12:31:32 volumio volumio-remote-updater[670]: [2025-12-26 12:31:32] [connect] Successful connection Dec 26 12:31:35 volumio kernel: sd 0:0:0:0: [sda] tag#12 uas_eh_abort_handler 0 uas-tag 19 inflight: CMD OUT Dec 26 12:31:35 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x41 41 00 01 83 e4 06 00 00 0e 00 Dec 26 12:31:35 volumio kernel: scsi host0: uas_eh_device_reset_handler start Dec 26 12:31:35 volumio kernel: usb 2-1: reset SuperSpeed USB device number 2 using xhci_hcd Dec 26 12:31:35 volumio kernel: scsi host0: uas_eh_device_reset_handler success Dec 26 12:31:35 volumio kernel: xhci_hcd 0000:01:00.0: WARNING: Host System Error Dec 26 12:31:40 volumio kernel: xhci_hcd 0000:01:00.0: xHCI host not responding to stop endpoint command Dec 26 12:31:40 volumio kernel: xhci_hcd 0000:01:00.0: xHCI host controller not responding, assume dead Dec 26 12:31:40 volumio kernel: xhci_hcd 0000:01:00.0: HC died; cleaning up Dec 26 12:31:40 volumio kernel: usb 1-1: USB disconnect, device number 2 Dec 26 12:31:40 volumio kernel: usb 1-1.3: USB disconnect, device number 3 Dec 26 12:31:40 volumio kernel: usb 2-1: cmd cmplt err -108 Dec 26 12:31:40 volumio kernel: usb 2-1: cmd cmplt err -108 Dec 26 12:31:40 volumio kernel: usb 2-1: cmd cmplt err -108 Dec 26 12:31:40 volumio kernel: usb 2-1: cmd cmplt err -108 Dec 26 12:31:40 volumio kernel: usb 2-1: USB disconnect, device number 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#1 uas_zap_pending 0 uas-tag 2 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x2a 2a 00 01 7f 70 70 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#2 uas_zap_pending 0 uas-tag 3 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 01 7f 70 5c 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#3 uas_zap_pending 0 uas-tag 4 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#1 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x2a 2a 00 01 7f 70 76 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#1 CDB: opcode=0x2a 2a 00 01 7f 70 70 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#4 uas_zap_pending 0 uas-tag 5 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x2a 2a 00 01 7f 70 7c 00 00 02 00 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25129072 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#5 uas_zap_pending 0 uas-tag 6 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x2a 2a 00 01 7f 8f 8c 00 00 02 00 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9830456, lost async page write Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#6 uas_zap_pending 0 uas-tag 7 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x2a 2a 00 01 7f 8f a8 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#7 uas_zap_pending 0 uas-tag 8 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x2a 2a 00 01 7f 8f de 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#8 uas_zap_pending 0 uas-tag 9 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#8 CDB: opcode=0x2a 2a 00 01 83 70 02 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#9 uas_zap_pending 0 uas-tag 10 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#2 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#9 CDB: opcode=0x2a 2a 00 01 83 70 22 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#2 CDB: opcode=0x2a 2a 00 01 7f 70 5c 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#10 uas_zap_pending 0 uas-tag 11 inflight: CMD Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25129052 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#10 CDB: opcode=0x2a 2a 00 01 83 70 54 00 00 04 00 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9830446, lost async page write Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#11 uas_zap_pending 0 uas-tag 12 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#11 CDB: opcode=0x2a 2a 00 01 7f 70 4e 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#14 uas_zap_pending 0 uas-tag 13 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#14 CDB: opcode=0x2a 2a 00 00 53 70 04 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#3 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#15 uas_zap_pending 0 uas-tag 14 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#3 CDB: opcode=0x2a 2a 00 01 7f 70 76 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#15 CDB: opcode=0x2a 2a 00 00 53 91 6a 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#12 uas_zap_pending 0 uas-tag 15 inflight: CMD Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25129078 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9830459, lost async page write Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#12 CDB: opcode=0x2a 2a 00 01 7f 70 52 00 00 06 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#13 uas_zap_pending 0 uas-tag 16 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#13 CDB: opcode=0x2a 2a 00 00 55 71 5e 00 00 28 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#4 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#16 uas_zap_pending 0 uas-tag 17 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#4 CDB: opcode=0x2a 2a 00 01 7f 70 7c 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 1e 38 28 00 00 80 00 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25129084 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#13 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=155s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#17 uas_zap_pending 0 uas-tag 18 inflight: CMD Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9830462, lost async page write Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#17 CDB: opcode=0x28 28 00 00 1e 38 c8 00 00 08 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#18 uas_zap_pending 0 uas-tag 19 inflight: CMD Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#13 CDB: opcode=0x2a 2a 00 00 55 71 5e 00 00 28 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x2a 2a 00 00 53 e0 1a 00 00 30 00 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 5599582 op 0x1:(WRITE) flags 0x800 phys_seg 20 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#5 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#5 CDB: opcode=0x2a 2a 00 01 7f 8f 8c 00 00 02 00 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25137036 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#18 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=157s Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9834438, lost async page write Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#18 CDB: opcode=0x2a 2a 00 00 53 e0 1a 00 00 30 00 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 5496858 op 0x1:(WRITE) flags 0x800 phys_seg 6 prio class 2 Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 10 writing to inode 2390458 starting block 2748429) Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#6 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#6 CDB: opcode=0x2a 2a 00 01 7f 8f a8 00 00 02 00 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14349 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14350 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25137064 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14351 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14352 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9834452, lost async page write Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14353 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#7 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=127s Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14354 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#7 CDB: opcode=0x2a 2a 00 01 7f 8f de 00 00 02 00 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#16 UNKNOWN(0x2003) Result: hostbyte=0x01 driverbyte=DRIVER_OK cmd_age=157s Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14355 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14356 Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 25137118 op 0x1:(WRITE) flags 0x103000 phys_seg 1 prio class 2 Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] tag#16 CDB: opcode=0x28 28 00 00 1e 38 28 00 00 80 00 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14357 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9834479, lost async page write Dec 26 12:31:40 volumio kernel: I/O error, dev sda, sector 1980456 op 0x0:(READ) flags 0x80700 phys_seg 16 prio class 2 Dec 26 12:31:40 volumio kernel: Buffer I/O error on device sda3, logical block 14358 Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9961473, lost async page write Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9961489, lost async page write Dec 26 12:31:40 volumio kernel: Buffer I/O error on dev sda3, logical block 9961514, lost async page write Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x15b1954e: -5 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): ext4_check_bdev_write_error:218: comm kworker/u8:2: Error while async write back metadata Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390456 starting block 12710402) Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390456 starting block 12710406) Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390471 starting block 12710410) Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390692 starting block 12710415) Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390472 starting block 2743293) Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): ext4_end_bio:347: I/O error 17 writing to inode 2390472 starting block 2743149) Dec 26 12:31:40 volumio kernel: Aborting journal on device sda3-8. Dec 26 12:31:40 volumio kernel: JBD2: I/O error when updating journal superblock for sda3-8. Dec 26 12:31:40 volumio kernel: JBD2: Detected IO errors while flushing file data on sda3-8 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): ext4_journal_check_start:83: comm smbd: Detected aborted journal Dec 26 12:31:40 volumio kernel: EXT4-fs (sda3): I/O error while writing superblock Dec 26 12:31:40 volumio kernel: EXT4-fs (sda3): Remounting filesystem read-only Dec 26 12:31:40 volumio kernel: EXT4-fs (sda3): I/O error while writing superblock Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x938e5e1: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [938e5e1] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [938e5e1] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 938e5e1, size 12adb Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x93b9a87: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [93b9a87] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [93b9a87] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 93b9a87, size 15781 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x15b2a486: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x15b2a486: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [15b2a486] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 15b2a486, size 3286 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [15b2a486] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 15b2a486, size 3286 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0xa2ff562: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [a2ff562] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [a2ff562] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block a2ff562, size 18237 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [a2ff562] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read fragment cache entry [a2ff562] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block a2ff562, size 18237 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0xa375fa4: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0xa375fa4: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [a375fa4] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block a375fa4, size deff Dec 26 12:31:40 volumio kernel: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 26 12:31:40 volumio kernel: CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers Dec 26 12:31:40 volumio kernel: CIFS: Attempting to mount \\10.0.2.10\share Dec 26 12:31:40 volumio smbd[1189]: [2025/12/26 12:31:40.469929, 0] ../source3/lib/util.c:493(reinit_after_fork) Dec 26 12:31:40 volumio volumio[885]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 12:31:40 volumio volumio[885]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 12:31:40 volumio volumio[885]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/cd_controller/package.json: EIO: i/o error, read Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/hi_res_audio/package.json: EIO: i/o error, read Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/qobuz/package.json: EIO: i/o error, read Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/smart_inputs/package.json: EIO: i/o error, read Dec 26 12:31:40 volumio volumio[885]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] The plugin system_controller/my_volumio failed to load, setting it to stopped. Error: Error: /myvolumio/plugins/system_controller/my_volumio/lib/my_volumio.node: cannot read file data: Input/output error Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0xa3292e5: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0xa3292e5: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [a3292e5] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block a3292e5, size de42 Dec 26 12:31:40 volumio systemd[1]: apt-daily.service: Control process exited, code=killed, status=7/BUS Dec 26 12:31:40 volumio nmbd[1190]: [2025/12/26 12:31:40.470652, 0] ../source3/lib/util.c:493(reinit_after_fork) Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] The plugin music_service/streaming_services failed to load, setting it to stopped. Error: Error: /myvolumio/plugins/music_service/streaming_services/lib/streaming_services.node: cannot read file data: Input/output error Dec 26 12:31:40 volumio volumio[885]: error: [MyVolumio PluginManager] !!!! WARNING !!!! Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 26 12:31:40 volumio volumio[885]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 26 12:31:40 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:40 volumio thd[669]: Error reading device '/dev/input/event0' Dec 26 12:31:40 volumio go-librespot[1195]: time="2025-12-26T12:31:40+09:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": net/http: TLS handshake timeout" Dec 26 12:31:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 26 12:31:40 volumio winbindd[1191]: [2025/12/26 12:31:40.471052, 0] ../source3/lib/util.c:493(reinit_after_fork) Dec 26 12:31:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:40 volumio sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=server,password=server,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=1.0 //10.0.2.10/share\\music /mnt/NAS/NAS Dec 26 12:31:40 volumio nmbd[793]: [2025/12/26 12:31:40.475476, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Dec 26 12:31:40 volumio nmbd[793]: tdb(/var/cache/samba/gencache.tdb): tdb_transaction: fsync failed Dec 26 12:31:40 volumio nmbd[793]: [2025/12/26 12:31:40.475651, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Dec 26 12:31:40 volumio nmbd[793]: tdb(/var/cache/samba/gencache.tdb): tdb_transaction_prepare_commit: failed to setup recovery data Dec 26 12:31:40 volumio sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:31:40 volumio nmbd[1288]: [2025/12/26 12:31:40.480765, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Dec 26 12:31:40 volumio nmbd[1288]: wins_write_database: Can't open /var/lib/samba/wins.dat.1288: Read-only file system Dec 26 12:31:40 volumio nmbd[793]: [2025/12/26 12:31:40.481530, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Dec 26 12:31:40 volumio nmbd[793]: tdb(/var/cache/samba/gencache.tdb): tdb_transaction: fsync failed Dec 26 12:31:40 volumio nmbd[793]: [2025/12/26 12:31:40.481728, 0] ../lib/tdb_wrap/tdb_wrap.c:64(tdb_wrap_log) Dec 26 12:31:40 volumio nmbd[793]: tdb(/var/cache/samba/gencache.tdb): tdb_transaction_prepare_commit: failed to setup recovery data Dec 26 12:31:40 volumio winbindd[1191]: messaging_reinit() failed: NT_STATUS_MEDIA_WRITE_PROTECTED Dec 26 12:31:40 volumio nmbd[1190]: messaging_reinit() failed: NT_STATUS_MEDIA_WRITE_PROTECTED Dec 26 12:31:40 volumio smbd[1189]: messaging_reinit() failed: NT_STATUS_MEDIA_WRITE_PROTECTED Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2422273: comm apt.systemd.dai: reading directory lblock 0 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2422275: comm apt.systemd.dai: reading directory lblock 0 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #18358273: comm apt.systemd.dai: reading directory lblock 0 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2390633: comm node: reading directory lblock 0 Dec 26 12:31:40 volumio systemd[1]: apt-daily.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 12:31:40 volumio apt.systemd.daily[1292]: /usr/lib/apt/apt.systemd.daily: 319: /usr/lib/apt/apt.systemd.daily: apt-config: Input/output error Dec 26 12:31:40 volumio volumio[885]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-id/usb-ADATA_SU_650_235678C218CA-0:0-part1 /dev/disk/by-label/boot /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-uuid/41CA-9A8B /dev/disk/by-partuuid/53ce6763-01","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"boot","ID_FS_LABEL_ENC":"boot","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"41CA-9A8B","ID_FS_UUID_ENC":"41CA-9A8B","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"650","ID_MODEL_ENC":"650\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8: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":"53ce6763-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"53ce6763","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"ADATA_SU_650_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ADATA_SU","ID_VENDOR_ENC":"ADATA\\x20SU","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2358","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3529470"} Dec 26 12:31:40 volumio systemd[1]: apt-daily.service: Failed with result 'exit-code'. Dec 26 12:31:40 volumio apt.systemd.daily[1292]: /usr/lib/apt/apt.systemd.daily: 320: /usr/lib/apt/apt.systemd.daily: cannot create /daily_lock: Input/output error Dec 26 12:31:40 volumio systemd[1]: Failed to start Daily apt download activities. Dec 26 12:31:40 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Dec 26 12:31:40 volumio systemd[1]: Stopped target Local File Systems. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1311]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1311]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: apt-daily-upgrade.service: Control process exited, code=killed, status=7/BUS Dec 26 12:31:40 volumio volumio[885]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio /dev/disk/by-uuid/0c3a77ec-ae84-4f1b-8028-c61cc251ffe5 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part2 /dev/disk/by-partuuid/53ce6763-02 /dev/disk/by-id/usb-ADATA_SU_650_235678C218CA-0:0-part2","DEVNAME":"/dev/sda2","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda2","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"volumio","ID_FS_LABEL_ENC":"volumio","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0c3a77ec-ae84-4f1b-8028-c61cc251ffe5","ID_FS_UUID_ENC":"0c3a77ec-ae84-4f1b-8028-c61cc251ffe5","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"650","ID_MODEL_ENC":"650\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8: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":"53ce6763-02","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"53ce6763","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"ADATA_SU_650_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ADATA_SU","ID_VENDOR_ENC":"ADATA\\x20SU","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"2","PARTN":"2","SEQNUM":"2359","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3533420"} Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2422275: comm (umount): reading directory lblock 0 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Failed to read block 0x15b2a486: -5 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [15b2a486] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 15b2a486, size 3286 Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read data cache entry [15b2a486] Dec 26 12:31:40 volumio kernel: SQUASHFS error: Unable to read page, block 15b2a486, size 3286 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2390633: comm node: reading directory lblock 0 Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio apt.systemd.daily[1313]: /usr/lib/apt/apt.systemd.daily: 319: /usr/lib/apt/apt.systemd.daily: apt-config: Input/output error Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio apt.systemd.daily[1313]: /usr/lib/apt/apt.systemd.daily: 320: /usr/lib/apt/apt.systemd.daily: cannot create /daily_lock: Input/output error Dec 26 12:31:40 volumio systemd[1315]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1315]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2422273: comm apt.systemd.dai: reading directory lblock 0 Dec 26 12:31:40 volumio kernel: EXT4-fs error (device sda3): __ext4_find_entry:1684: inode #2422275: comm apt.systemd.dai: reading directory lblock 0 Dec 26 12:31:40 volumio systemd[1]: apt-daily-upgrade.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Dec 26 12:31:40 volumio systemd[1]: apt-daily-upgrade.service: Failed with result 'exit-code'. Dec 26 12:31:40 volumio systemd[1]: Failed to start Daily apt upgrade and clean activities. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1320]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1320]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio sudo[1193]: pam_unix(sudo:session): session closed for user root Dec 26 12:31:40 volumio systemd[1321]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1321]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] Synchronizing SCSI cache Dec 26 12:31:40 volumio systemd[1322]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1322]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:40 volumio volumio[885]: info: Partition removed: {"syspath":"/sys/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","ACTION":"remove","DEVLINKS":"/dev/disk/by-label/volumio_data /dev/disk/by-partuuid/53ce6763-03 /dev/disk/by-id/usb-ADATA_SU_650_235678C218CA-0:0-part3 /dev/disk/by-uuid/0324d53a-d066-482b-b659-eb98af2b49e7 /dev/disk/by-path/platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0-part3","DEVNAME":"/dev/sda3","DEVPATH":"/devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb2/2-1/2-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda3","DEVTYPE":"partition","DISKSEQ":"25","ID_BUS":"usb","ID_FS_LABEL":"volumio_data","ID_FS_LABEL_ENC":"volumio_data","ID_FS_TYPE":"ext4","ID_FS_USAGE":"filesystem","ID_FS_UUID":"0324d53a-d066-482b-b659-eb98af2b49e7","ID_FS_UUID_ENC":"0324d53a-d066-482b-b659-eb98af2b49e7","ID_FS_VERSION":"1.0","ID_INSTANCE":"0:0","ID_MODEL":"650","ID_MODEL_ENC":"650\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"a2a4","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_NUMBER":"3","ID_PART_ENTRY_OFFSET":"5468160","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"228973247","ID_PART_ENTRY_TYPE":"0x83","ID_PART_ENTRY_UUID":"53ce6763-03","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"53ce6763","ID_PATH":"platform-fd500000.pcie-pci-0000:01:00.0-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-fd500000_pcie-pci-0000_01_00_0-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"4101","ID_SERIAL":"ADATA_SU_650_235678C218CA-0:0","ID_SERIAL_SHORT":"235678C218CA","ID_TYPE":"disk","ID_USB_DRIVER":"uas","ID_USB_INTERFACES":":080650:080662:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"ADATA_SU","ID_VENDOR_ENC":"ADATA\\x20SU","ID_VENDOR_ID":"7825","MAJOR":"8","MINOR":"3","PARTN":"3","SEQNUM":"2360","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"3535597"} Dec 26 12:31:40 volumio systemd[1323]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1323]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:31:40 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:31:40 volumio systemd[1324]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1324]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio volumio[885]: info: Listing playlists Dec 26 12:31:40 volumio volumio[885]: info: Listing playlists Dec 26 12:31:40 volumio systemd[1325]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1325]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio volumio[885]: info: Dec 26 12:31:40 volumio volumio[885]: ---------------------------- USB Audio Device Detached Dec 26 12:31:40 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Dec 26 12:31:40 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:31:40 volumio systemd[1326]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1326]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:31:40 volumio systemd[1329]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1329]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1331]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1331]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 26 12:31:40 volumio volumio[885]: info: No valid Plugin REST Endpoint Dec 26 12:31:40 volumio systemd[1332]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1332]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1333]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1333]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1334]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1334]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1335]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1335]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1336]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1336]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device. Stopping, too. Dec 26 12:31:40 volumio systemd[1]: Unmounting /boot... Dec 26 12:31:40 volumio systemd[1337]: boot.mount: Failed to execute command: Input/output error Dec 26 12:31:40 volumio systemd[1337]: boot.mount: Failed at step EXEC spawning /bin/umount: Input/output error Dec 26 12:31:40 volumio systemd[1]: boot.mount: Mount process exited, code=exited, status=203/EXEC Dec 26 12:31:40 volumio systemd[1]: Failed unmounting /boot. Dec 26 12:31:40 volumio systemd[1]: boot.mount: Unit is bound to inactive unit dev-disk-by\x2duuid-41CA\x2d9A8B.device, but not stopping since we tried this too often recently. Dec 26 12:31:40 volumio systemd[1]: systemd-fsck@dev-disk-by\x2duuid-41CA\x2d9A8B.service: Succeeded. Dec 26 12:31:40 volumio systemd[1]: Stopped File System Check on /dev/disk/by-uuid/41CA-9A8B. Dec 26 12:31:40 volumio kernel: sd 0:0:0:0: [sda] Synchronize Cache(10) failed: Result: hostbyte=0x07 driverbyte=DRIVER_OK Dec 26 12:31:40 volumio kernel: xhci_hcd 0000:01:00.0: WARN Can't disable streams for endpoint 0x82, streams are being disabled already Dec 26 12:31:41 volumio volumio[885]: Configuration write error: Error: EROFS: read-only file system, open '/data/configuration/plugins.json' Dec 26 12:31:42 volumio volumio[885]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 26 12:31:42 volumio volumio[885]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 12:31:42 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:31:42 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:31:43 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:43 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:43 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 26 12:31:43 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:31:43 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:31:43 volumio go-librespot[1338]: Librespot-go daemon starting... Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=info msg="generated new device id: 270a7e6158f1d5bf8d84eb0154c898cefc15b503" Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:31:43 volumio go-librespot[1338]: time="2025-12-26T12:31:43+09:00" level=debug msg="zeroconf server listening on port 46845" Dec 26 12:31:43 volumio go-librespot[1338]: Bus error Dec 26 12:31:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:31:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:46 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:46 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:46 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:46 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Dec 26 12:31:46 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:31:47 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:31:47 volumio go-librespot[1346]: Librespot-go daemon starting... Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=info msg="generated new device id: add87105932affb5be1abc661f0342180de27436" Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:31:47 volumio go-librespot[1346]: time="2025-12-26T12:31:47+09:00" level=debug msg="zeroconf server listening on port 39925" Dec 26 12:31:47 volumio go-librespot[1346]: unexpected fault address 0x518918 Dec 26 12:31:47 volumio go-librespot[1346]: fatal error: fault Dec 26 12:31:47 volumio go-librespot[1346]: [signal SIGBUS: bus error code=0x2 addr=0x518918 pc=0x518918] Dec 26 12:31:47 volumio go-librespot[1346]: goroutine 51 gp=0x1403568 m=0 mp=0xaa9b38 [running]: Dec 26 12:31:47 volumio go-librespot[1346]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x1533cbc sp=0x1533ca8 pc=0x58e88 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.sigpanic() Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x1533cec sp=0x1533cbc pc=0x740d0 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*SRV).len(0x14a1650, 0x35, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x1533cf0 sp=0x1533cf0 pc=0x518918 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.msgLenWithCompressionMap(0x1533f70, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x1533d38 sp=0x1533cf0 pc=0x4eb524 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x1533f70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x1533da8 sp=0x1533d38 pc=0x4ea220 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).PackBuffer(0x1533f70, {0x0, 0x0, 0x0}) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x1533ddc sp=0x1533da8 pc=0x4ea050 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x15fa4c0, 0x1533f70, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x1533e64 sp=0x1533ddc pc=0x526c2c Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.(*Server).probe(0x15fa4c0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x1533fe4 sp=0x1533e64 pc=0x525934 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x1533fec sp=0x1533fe4 pc=0x523708 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.goexit({}) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1533fec sp=0x1533fec pc=0x9346c Dec 26 12:31:47 volumio go-librespot[1346]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:31:47 volumio go-librespot[1346]: goroutine 1 gp=0x1402128 m=nil [runnable]: Dec 26 12:31:47 volumio go-librespot[1346]: google.golang.org/protobuf/encoding/protowire.ConsumeTag({0xa81b4d, 0xc, 0x611}) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/google.golang.org/protobuf@v1.30.0/encoding/protowire/wire.go:168 +0xe4 fp=0x15d1594 sp=0x15d1594 pc=0x169cb0 Dec 26 12:31:47 volumio go-librespot[1346]: panic during panic Dec 26 12:31:47 volumio go-librespot[1346]: SIGBUS: bus error Dec 26 12:31:47 volumio go-librespot[1346]: PC=0x8422c m=0 sigcode=2 addr=0x98ea48 Dec 26 12:31:47 volumio go-librespot[1346]: goroutine 0 gp=0xaa9290 m=0 mp=0xaa9b38 [idle]: Dec 26 12:31:47 volumio go-librespot[1346]: runtime.(*unwinder).resolveInternal(0xbed7fa1c, 0x0, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:256 +0x18 fp=0xbed7f81c sp=0xbed7f7d8 pc=0x8422c Dec 26 12:31:47 volumio go-librespot[1346]: runtime.(*unwinder).next(0xbed7fa1c) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:512 +0x198 fp=0xbed7f868 sp=0xbed7f81c pc=0x84730 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.traceback2(0xbed7fa1c, 0x0, 0x0, 0x31) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:981 +0x88 fp=0xbed7f9a0 sp=0xbed7f868 pc=0x85fc8 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.traceback1.func1(0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:917 +0x6c fp=0xbed7fa00 sp=0xbed7f9a0 pc=0x85e1c Dec 26 12:31:47 volumio go-librespot[1346]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x1402128, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:940 +0x1a4 fp=0xbed7fb08 sp=0xbed7fa00 pc=0x85d0c Dec 26 12:31:47 volumio go-librespot[1346]: runtime.traceback(...) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:817 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.tracebackothers.func1(0x1402128) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1259 +0x118 fp=0xbed7fb24 sp=0xbed7fb08 pc=0x87714 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.forEachGRace(0xbed7fb5c) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:677 +0x4c fp=0xbed7fb3c sp=0xbed7fb24 pc=0x5d378 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.tracebackothers(0x1403568) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1245 +0xcc fp=0xbed7fb6c sp=0xbed7fb3c pc=0x875e4 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.dopanic_m(0x1403568, 0x58e88, 0x1533ca8) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1345 +0x2a8 fp=0xbed7fba0 sp=0xbed7fb6c pc=0x59958 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.fatalthrow.func1() Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1199 +0x64 fp=0xbed7fbc0 sp=0xbed7fba0 pc=0x59388 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.systemstack(0xaa9290) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xbed7fbc4 sp=0xbed7fbc0 pc=0x91818 Dec 26 12:31:47 volumio go-librespot[1346]: goroutine 51 gp=0x1403568 m=0 mp=0xaa9b38 [running]: Dec 26 12:31:47 volumio go-librespot[1346]: runtime.systemstack_switch() Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x1533c8c sp=0x1533c88 pc=0x917ac Dec 26 12:31:47 volumio go-librespot[1346]: runtime.fatalthrow(0x2) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1192 +0x64 fp=0x1533ca8 sp=0x1533c8c pc=0x5930c Dec 26 12:31:47 volumio go-librespot[1346]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x1533cbc sp=0x1533ca8 pc=0x58e88 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.sigpanic() Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x1533cec sp=0x1533cbc pc=0x740d0 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*SRV).len(0x14a1650, 0x35, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x1533cf0 sp=0x1533cf0 pc=0x518918 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.msgLenWithCompressionMap(0x1533f70, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x1533d38 sp=0x1533cf0 pc=0x4eb524 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x1533f70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x1533da8 sp=0x1533d38 pc=0x4ea220 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).PackBuffer(0x1533f70, {0x0, 0x0, 0x0}) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x1533ddc sp=0x1533da8 pc=0x4ea050 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x15fa4c0, 0x1533f70, 0x0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x1533e64 sp=0x1533ddc pc=0x526c2c Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.(*Server).probe(0x15fa4c0) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x1533fe4 sp=0x1533e64 pc=0x525934 Dec 26 12:31:47 volumio go-librespot[1346]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x1533fec sp=0x1533fe4 pc=0x523708 Dec 26 12:31:47 volumio go-librespot[1346]: runtime.goexit({}) Dec 26 12:31:47 volumio go-librespot[1346]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1533fec sp=0x1533fec pc=0x9346c Dec 26 12:31:47 volumio go-librespot[1346]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:31:47 volumio go-librespot[1346]: goroutine 1 gp=0x1402128 m=nil [runnable]: Dec 26 12:31:47 volumio go-librespot[1346]: google.golang.org/protobuf/encoding/protowire.ConsumeTag({0xa81b4d, 0xc, 0x611}) Dec 26 12:31:47 volumio go-librespot[1346]: /home/runner/go/pkg/mod/google.golang.org/protobuf@v1.30.0/encoding/protowire/wire.go:168 +0xe4 fp=0x15d1594 sp=0x15d1594 pc=0x169cb0 Dec 26 12:31:47 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:31:47 volumio go-librespot[1346]: Bus error Dec 26 12:31:47 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:47 volumio volumio-remote-updater[670]: [2025-12-26 12:31:47] [connect] Successful connection Dec 26 12:31:47 volumio volumio-remote-updater[670]: [2025-12-26 12:31:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766719907 101 Dec 26 12:31:47 volumio volumio[885]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 3 Dec 26 12:31:48 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:31:48 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:31:49 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:49 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:50 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:50 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Dec 26 12:31:50 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:31:50 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:31:50 volumio go-librespot[1392]: Librespot-go daemon starting... Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=info msg="generated new device id: d19a9300c7c70a92a083dd4f2faa6b18a257d77c" Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 12:31:50 volumio go-librespot[1392]: time="2025-12-26T12:31:50+09:00" level=debug msg="zeroconf server listening on port 41025" Dec 26 12:31:50 volumio go-librespot[1392]: unexpected fault address 0x518918Bus error Dec 26 12:31:50 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:31:50 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:52 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:52 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:53 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:53 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Dec 26 12:31:53 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:31:53 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:31:53 volumio go-librespot[1400]: Librespot-go daemon starting... Dec 26 12:31:53 volumio go-librespot[1400]: time="2025-12-26T12:31:53+09:00" level=info msg="generated new device id: 3a587dc2dd9446a12c7c99a09762dfa9bc387e36" Dec 26 12:31:53 volumio go-librespot[1400]: time="2025-12-26T12:31:53+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:31:53 volumio go-librespot[1400]: Bus error Dec 26 12:31:53 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:31:53 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:55 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:55 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:56 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:56 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Dec 26 12:31:56 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:31:56 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:31:56 volumio go-librespot[1408]: Librespot-go daemon starting... Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=info msg="generated new device id: d30540581567220c9649b8712e064761708b28aa" Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:31:56 volumio go-librespot[1408]: time="2025-12-26T12:31:56+09:00" level=debug msg="zeroconf server listening on port 35561" Dec 26 12:31:56 volumio go-librespot[1408]: Bus error Dec 26 12:31:56 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:31:56 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:31:58 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:31:58 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:31:58 volumio volumio[885]: info: Listing playlists Dec 26 12:31:58 volumio volumio[885]: info: Listing playlists Dec 26 12:31:58 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:31:58 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:31:58 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:31:58 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:31:59 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:31:59 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Dec 26 12:31:59 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:00 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:00 volumio go-librespot[1416]: Librespot-go daemon starting... Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=info msg="generated new device id: eef41637cf2fc232f7023c436d55a80d3d5c748c" Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:00 volumio go-librespot[1416]: time="2025-12-26T12:32:00+09:00" level=debug msg="zeroconf server listening on port 33119" Dec 26 12:32:00 volumio go-librespot[1416]: Bus error Dec 26 12:32:00 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:00 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:00 volumio volumio[885]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 26 12:32:00 volumio volumio[885]: info: Completed starting MyVolumio Plugin Dec 26 12:32:00 volumio volumio[885]: info: BOOT COMPLETED Dec 26 12:32:00 volumio volumio[885]: [Metrics] CommandRouter: 248s 406.88ms Dec 26 12:32:00 volumio volumio[885]: info: CoreCommandRouter::volumiosetStartupVolume Dec 26 12:32:00 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 12:32:00 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 12:32:00 volumio volumio[885]: info: CoreCommandRouter::Close All Modals sent Dec 26 12:32:00 volumio volumio[885]: info: CoreCommandRouter::Close All Modals sent Dec 26 12:32:00 volumio volumio[885]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Dec 26 12:32:00 volumio volumio[885]: ALSA lib pcm_hw.c:1822:(_snd_pcm_hw_open) Invalid value for card Dec 26 12:32:00 volumio volumio[885]: aplay: main:828: audio open error: No such device Dec 26 12:32:01 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 26 12:32:01 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 26 12:32:01 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Dec 26 12:32:01 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 26 12:32:01 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:01 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:03 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:03 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Dec 26 12:32:03 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:03 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:03 volumio go-librespot[1428]: Librespot-go daemon starting... Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=info msg="generated new device id: 366d269c192894adb7cd436aacd4f7efad3034db" Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:03 volumio go-librespot[1428]: time="2025-12-26T12:32:03+09:00" level=debug msg="zeroconf server listening on port 33949" Dec 26 12:32:03 volumio go-librespot[1428]: Bus error Dec 26 12:32:03 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:03 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:04 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:04 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:06 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:06 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 10. Dec 26 12:32:06 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:06 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:06 volumio go-librespot[1436]: Librespot-go daemon starting... Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=info msg="generated new device id: d4a860a61fc6d1e78fb9f5ad55055ec93130f587" Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 12:32:06 volumio go-librespot[1436]: time="2025-12-26T12:32:06+09:00" level=debug msg="zeroconf server listening on port 39953" Dec 26 12:32:06 volumio go-librespot[1436]: unexpected fault address 0x518918 Dec 26 12:32:06 volumio go-librespot[1436]: fatal error: fault Dec 26 12:32:06 volumio go-librespot[1436]: [signal SIGBUS: bus error code=0x2 addr=0x518918 pc=0x518918] Dec 26 12:32:06 volumio go-librespot[1436]: goroutine 25 gp=0x1803328 m=4 mp=0x1847088 [running]: Dec 26 12:32:06 volumio go-librespot[1436]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x19a9cbc sp=0x19a9ca8 pc=0x58e88 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.sigpanic() Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x19a9cec sp=0x19a9cbc pc=0x740d0 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*SRV).len(0x19ae180, 0x35, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x19a9cf0 sp=0x19a9cf0 pc=0x518918 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.msgLenWithCompressionMap(0x19a9f70, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x19a9d38 sp=0x19a9cf0 pc=0x4eb524 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x19a9f70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x19a9da8 sp=0x19a9d38 pc=0x4ea220 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).PackBuffer(0x19a9f70, {0x0, 0x0, 0x0}) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x19a9ddc sp=0x19a9da8 pc=0x4ea050 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x191a580, 0x19a9f70, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x19a9e64 sp=0x19a9ddc pc=0x526c2c Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.(*Server).probe(0x191a580) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x19a9fe4 sp=0x19a9e64 pc=0x525934 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x19a9fec sp=0x19a9fe4 pc=0x523708 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.goexit({}) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x19a9fec sp=0x19a9fec pc=0x9346c Dec 26 12:32:06 volumio go-librespot[1436]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:06 volumio go-librespot[1436]: goroutine 1 gp=0x1802128 m=nil [runnable]: Dec 26 12:32:06 volumio go-librespot[1436]: panic during panic Dec 26 12:32:06 volumio go-librespot[1436]: SIGBUS: bus error Dec 26 12:32:06 volumio go-librespot[1436]: PC=0x1b218 m=4 sigcode=2 addr=0x77cd96 Dec 26 12:32:06 volumio go-librespot[1436]: goroutine 0 gp=0x1802c68 m=4 mp=0x1847088 [idle]: Dec 26 12:32:06 volumio go-librespot[1436]: indexbytebody() Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/bytealg/indexbyte_arm.s:34 +0x10 fp=0xb54fea60 sp=0xb54fea60 pc=0x1b218 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.findnull(0x77cd96) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/string.go:541 +0x88 fp=0xb54fea88 sp=0xb54fea60 pc=0x7a9d8 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.gostringnocopy(...) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/string.go:565 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.(*moduledata).funcName(0xa70d20, 0x453fe) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/symtab.go:642 +0x40 fp=0xb54feaa0 sp=0xb54fea88 pc=0x7be70 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.srcFunc.name(...) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/symtab.go:820 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.traceback2(0xb54fec54, 0x0, 0x0, 0x31) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:998 +0x350 fp=0xb54febd8 sp=0xb54feaa0 pc=0x86290 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.traceback1.func1(0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:917 +0x6c fp=0xb54fec38 sp=0xb54febd8 pc=0x85e1c Dec 26 12:32:06 volumio go-librespot[1436]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x1802128, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:940 +0x1a4 fp=0xb54fed40 sp=0xb54fec38 pc=0x85d0c Dec 26 12:32:06 volumio go-librespot[1436]: runtime.traceback(...) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:817 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.tracebackothers.func1(0x1802128) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1259 +0x118 fp=0xb54fed5c sp=0xb54fed40 pc=0x87714 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.forEachGRace(0xb54fed94) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:677 +0x4c fp=0xb54fed74 sp=0xb54fed5c pc=0x5d378 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.tracebackothers(0x1803328) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1245 +0xcc fp=0xb54feda4 sp=0xb54fed74 pc=0x875e4 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.dopanic_m(0x1803328, 0x58e88, 0x19a9ca8) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1345 +0x2a8 fp=0xb54fedd8 sp=0xb54feda4 pc=0x59958 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.fatalthrow.func1() Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1199 +0x64 fp=0xb54fedf8 sp=0xb54fedd8 pc=0x59388 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.systemstack(0x1000152) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xb54fedfc sp=0xb54fedf8 pc=0x91818 Dec 26 12:32:06 volumio go-librespot[1436]: goroutine 25 gp=0x1803328 m=4 mp=0x1847088 [running]: Dec 26 12:32:06 volumio go-librespot[1436]: runtime.systemstack_switch() Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x19a9c8c sp=0x19a9c88 pc=0x917ac Dec 26 12:32:06 volumio go-librespot[1436]: runtime.fatalthrow(0x2) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1192 +0x64 fp=0x19a9ca8 sp=0x19a9c8c pc=0x5930c Dec 26 12:32:06 volumio go-librespot[1436]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x19a9cbc sp=0x19a9ca8 pc=0x58e88 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.sigpanic() Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x19a9cec sp=0x19a9cbc pc=0x740d0 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*SRV).len(0x19ae180, 0x35, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x19a9cf0 sp=0x19a9cf0 pc=0x518918 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.msgLenWithCompressionMap(0x19a9f70, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x19a9d38 sp=0x19a9cf0 pc=0x4eb524 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x19a9f70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x19a9da8 sp=0x19a9d38 pc=0x4ea220 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).PackBuffer(0x19a9f70, {0x0, 0x0, 0x0}) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x19a9ddc sp=0x19a9da8 pc=0x4ea050 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x191a580, 0x19a9f70, 0x0) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x19a9e64 sp=0x19a9ddc pc=0x526c2c Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.(*Server).probe(0x191a580) Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x19a9fe4 sp=0x19a9e64 pc=0x525934 Dec 26 12:32:06 volumio go-librespot[1436]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x19a9fec sp=0x19a9fe4 pc=0x523708 Dec 26 12:32:06 volumio go-librespot[1436]: runtime.goexit({}) Dec 26 12:32:06 volumio go-librespot[1436]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x19a9fec sp=0x19a9fec pc=0x9346c Dec 26 12:32:06 volumio go-librespot[1436]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:06 volumio go-librespot[1436]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:06 volumio go-librespot[1436]: goroutine 1 gp=0x1802128 m=nil [runnable]: Dec 26 12:32:06 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:06 volumio go-librespot[1436]: Bus error Dec 26 12:32:06 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:07 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:07 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:08 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:08 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:09 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:09 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 11. Dec 26 12:32:09 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:09 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:09 volumio go-librespot[1444]: Librespot-go daemon starting... Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=info msg="generated new device id: b8b8b911b6224477d159efd254b559ac37d7dd1b" Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:09 volumio go-librespot[1444]: time="2025-12-26T12:32:09+09:00" level=debug msg="zeroconf server listening on port 42951" Dec 26 12:32:09 volumio go-librespot[1444]: unexpected fault address Bus error Dec 26 12:32:09 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:09 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:10 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:10 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:12 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:12 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 12. Dec 26 12:32:12 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:13 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:13 volumio go-librespot[1452]: Librespot-go daemon starting... Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=info msg="generated new device id: 89622fdb4848e756de34a3841453a32da8fc9ccd" Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:13 volumio go-librespot[1452]: time="2025-12-26T12:32:13+09:00" level=debug msg="zeroconf server listening on port 36233" Dec 26 12:32:13 volumio go-librespot[1452]: unexpected fault address 0x518918 Dec 26 12:32:13 volumio go-librespot[1452]: fatal error: fault Dec 26 12:32:13 volumio go-librespot[1452]: Bus error Dec 26 12:32:13 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:13 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:13 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:13 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:14 volumio nmbd[1460]: [2025/12/26 12:32:14.531306, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Dec 26 12:32:14 volumio nmbd[1460]: wins_write_database: Can't open /var/lib/samba/wins.dat.1460: Read-only file system Dec 26 12:32:16 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:16 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 13. Dec 26 12:32:16 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:16 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:16 volumio go-librespot[1461]: Librespot-go daemon starting... Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=info msg="generated new device id: 6abce44f1a0a940a86b5b21d75e59a8dc76bbce1" Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:16 volumio go-librespot[1461]: time="2025-12-26T12:32:16+09:00" level=debug msg="zeroconf server listening on port 35329" Dec 26 12:32:16 volumio go-librespot[1461]: unexpected fault address 0x518918 Dec 26 12:32:16 volumio go-librespot[1461]: fatal error: fault Dec 26 12:32:16 volumio go-librespot[1461]: [signal SIGBUS: bus error code=0x2 addr=0x518918 pc=0x518918] Dec 26 12:32:16 volumio go-librespot[1461]: goroutine 53 gp=0x1403328 m=0 mp=0xaa9b38 [running]: Dec 26 12:32:16 volumio go-librespot[1461]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x15abcbc sp=0x15abca8 pc=0x58e88 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.sigpanic() Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x15abcec sp=0x15abcbc pc=0x740d0 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*SRV).len(0x15aeb40, 0x35, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x15abcf0 sp=0x15abcf0 pc=0x518918 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.msgLenWithCompressionMap(0x15abf70, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x15abd38 sp=0x15abcf0 pc=0x4eb524 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x15abf70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x15abda8 sp=0x15abd38 pc=0x4ea220 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).PackBuffer(0x15abf70, {0x0, 0x0, 0x0}) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x15abddc sp=0x15abda8 pc=0x4ea050 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x152c400, 0x15abf70, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x15abe64 sp=0x15abddc pc=0x526c2c Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.(*Server).probe(0x152c400) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x15abfe4 sp=0x15abe64 pc=0x525934 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x15abfec sp=0x15abfe4 pc=0x523708 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.goexit({}) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x15abfec sp=0x15abfec pc=0x9346c Dec 26 12:32:16 volumio go-librespot[1461]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:16 volumio go-librespot[1461]: goroutine 1 gp=0x1402128 m=nil [runnable]: Dec 26 12:32:16 volumio go-librespot[1461]: panic during panic Dec 26 12:32:16 volumio go-librespot[1461]: SIGBUS: bus error Dec 26 12:32:16 volumio go-librespot[1461]: PC=0x8422c m=0 sigcode=2 addr=0x98e4b8 Dec 26 12:32:16 volumio go-librespot[1461]: goroutine 0 gp=0xaa9290 m=0 mp=0xaa9b38 [idle]: Dec 26 12:32:16 volumio go-librespot[1461]: runtime.(*unwinder).resolveInternal(0xbe859a1c, 0x1, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:256 +0x18 fp=0xbe859928 sp=0xbe8598e4 pc=0x8422c Dec 26 12:32:16 volumio go-librespot[1461]: runtime.(*unwinder).initAt(0xbe859a1c, 0xffffffff, 0xffffffff, 0x0, 0x1402128, 0x1) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:223 +0x1d0 fp=0xbe8599a0 sp=0xbe859928 pc=0x840d0 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.traceback1.func1(0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:916 +0x48 fp=0xbe859a00 sp=0xbe8599a0 pc=0x85df8 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x1402128, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:940 +0x1a4 fp=0xbe859b08 sp=0xbe859a00 pc=0x85d0c Dec 26 12:32:16 volumio go-librespot[1461]: runtime.traceback(...) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:817 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.tracebackothers.func1(0x1402128) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1259 +0x118 fp=0xbe859b24 sp=0xbe859b08 pc=0x87714 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.forEachGRace(0xbe859b5c) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:677 +0x4c fp=0xbe859b3c sp=0xbe859b24 pc=0x5d378 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.tracebackothers(0x1403328) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1245 +0xcc fp=0xbe859b6c sp=0xbe859b3c pc=0x875e4 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.dopanic_m(0x1403328, 0x58e88, 0x15abca8) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1345 +0x2a8 fp=0xbe859ba0 sp=0xbe859b6c pc=0x59958 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.fatalthrow.func1() Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1199 +0x64 fp=0xbe859bc0 sp=0xbe859ba0 pc=0x59388 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.systemstack(0xaa9290) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xbe859bc4 sp=0xbe859bc0 pc=0x91818 Dec 26 12:32:16 volumio go-librespot[1461]: goroutine 53 gp=0x1403328 m=0 mp=0xaa9b38 [running]: Dec 26 12:32:16 volumio go-librespot[1461]: runtime.systemstack_switch() Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x15abc8c sp=0x15abc88 pc=0x917ac Dec 26 12:32:16 volumio go-librespot[1461]: runtime.fatalthrow(0x2) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1192 +0x64 fp=0x15abca8 sp=0x15abc8c pc=0x5930c Dec 26 12:32:16 volumio go-librespot[1461]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x15abcbc sp=0x15abca8 pc=0x58e88 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.sigpanic() Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x15abcec sp=0x15abcbc pc=0x740d0 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*SRV).len(0x15aeb40, 0x35, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x15abcf0 sp=0x15abcf0 pc=0x518918 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.msgLenWithCompressionMap(0x15abf70, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x15abd38 sp=0x15abcf0 pc=0x4eb524 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x15abf70, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x15abda8 sp=0x15abd38 pc=0x4ea220 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).PackBuffer(0x15abf70, {0x0, 0x0, 0x0}) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x15abddc sp=0x15abda8 pc=0x4ea050 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x152c400, 0x15abf70, 0x0) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x15abe64 sp=0x15abddc pc=0x526c2c Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.(*Server).probe(0x152c400) Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x15abfe4 sp=0x15abe64 pc=0x525934 Dec 26 12:32:16 volumio go-librespot[1461]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x15abfec sp=0x15abfe4 pc=0x523708 Dec 26 12:32:16 volumio go-librespot[1461]: runtime.goexit({}) Dec 26 12:32:16 volumio go-librespot[1461]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x15abfec sp=0x15abfec pc=0x9346c Dec 26 12:32:16 volumio go-librespot[1461]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:16 volumio go-librespot[1461]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:16 volumio go-librespot[1461]: goroutine 1 gp=0x1402128 m=nil [runnable]: Dec 26 12:32:16 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:16 volumio go-librespot[1461]: Bus error Dec 26 12:32:16 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:16 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:16 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:18 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:18 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:18 volumio volumio[885]: info: Listing playlists Dec 26 12:32:18 volumio volumio[885]: info: Listing playlists Dec 26 12:32:18 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:32:18 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390552: lblock 0: comm node: error -5 reading directory block Dec 26 12:32:19 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:19 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 14. Dec 26 12:32:19 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:19 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:19 volumio go-librespot[1469]: Librespot-go daemon starting... Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=info msg="generated new device id: ec4d7606e3c7d80b73507b7fb2b3933c1901025a" Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 12:32:19 volumio go-librespot[1469]: time="2025-12-26T12:32:19+09:00" level=debug msg="zeroconf server listening on port 37391" Dec 26 12:32:19 volumio go-librespot[1469]: Bus error Dec 26 12:32:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:19 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:19 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 15. Dec 26 12:32:22 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:22 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:22 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:22 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:22 volumio go-librespot[1477]: Librespot-go daemon starting... Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=info msg="generated new device id: 42656ad2f365ee875114395e4908dca905bff4ad" Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:22 volumio go-librespot[1477]: time="2025-12-26T12:32:22+09:00" level=debug msg="zeroconf server listening on port 34025" Dec 26 12:32:22 volumio go-librespot[1477]: unexpected fault address 0x518918 Dec 26 12:32:22 volumio go-librespot[1477]: fatal error: fault Dec 26 12:32:22 volumio go-librespot[1477]: Bus error Dec 26 12:32:22 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:22 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:25 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:25 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:25 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:25 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 16. Dec 26 12:32:25 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:26 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:26 volumio go-librespot[1485]: Librespot-go daemon starting... Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=info msg="generated new device id: 327dd94dc8b0c0d67ca1464a4dd0c2b62e032151" Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 26 12:32:26 volumio go-librespot[1485]: time="2025-12-26T12:32:26+09:00" level=debug msg="zeroconf server listening on port 38559" Dec 26 12:32:26 volumio go-librespot[1485]: unexpected fault address 0x518918 Dec 26 12:32:26 volumio go-librespot[1485]: fatal error: fault Dec 26 12:32:26 volumio go-librespot[1485]: [signal SIGBUS: bus error code=0x2 addr=0x518918 pc=0x518918] Dec 26 12:32:26 volumio go-librespot[1485]: goroutine 35 gp=0x2003448 m=5 mp=0x2080008 [running]: Dec 26 12:32:26 volumio go-librespot[1485]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x22a34bc sp=0x22a34a8 pc=0x58e88 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.sigpanic() Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x22a34ec sp=0x22a34bc pc=0x740d0 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*SRV).len(0x2000840, 0x35, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x22a34f0 sp=0x22a34f0 pc=0x518918 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.msgLenWithCompressionMap(0x22a3770, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x22a3538 sp=0x22a34f0 pc=0x4eb524 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x22a3770, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x22a35a8 sp=0x22a3538 pc=0x4ea220 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).PackBuffer(0x22a3770, {0x0, 0x0, 0x0}) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x22a35dc sp=0x22a35a8 pc=0x4ea050 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x2402400, 0x22a3770, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x22a3664 sp=0x22a35dc pc=0x526c2c Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.(*Server).probe(0x2402400) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x22a37e4 sp=0x22a3664 pc=0x525934 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x22a37ec sp=0x22a37e4 pc=0x523708 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.goexit({}) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x22a37ec sp=0x22a37ec pc=0x9346c Dec 26 12:32:26 volumio go-librespot[1485]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:26 volumio go-librespot[1485]: goroutine 1 gp=0x2002128 m=nil [runnable]: Dec 26 12:32:26 volumio go-librespot[1485]: google.golang.org/protobuf/encoding/protowire.ConsumeTag({0xa8205f, 0x47, 0xff}) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/google.golang.org/protobuf@v1.30.0/encoding/protowire/wire.go:168 +0xe4 fp=0x214967c sp=0x214967c pc=0x169cb0 Dec 26 12:32:26 volumio go-librespot[1485]: panic during panic Dec 26 12:32:26 volumio go-librespot[1485]: SIGBUS: bus error Dec 26 12:32:26 volumio go-librespot[1485]: PC=0x8422c m=5 sigcode=2 addr=0x98e628 Dec 26 12:32:26 volumio go-librespot[1485]: goroutine 0 gp=0x2084128 m=5 mp=0x2080008 [idle]: Dec 26 12:32:26 volumio go-librespot[1485]: runtime.(*unwinder).resolveInternal(0xb4cfdc54, 0x0, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:256 +0x18 fp=0xb4cfda54 sp=0xb4cfda10 pc=0x8422c Dec 26 12:32:26 volumio go-librespot[1485]: runtime.(*unwinder).next(0xb4cfdc54) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:512 +0x198 fp=0xb4cfdaa0 sp=0xb4cfda54 pc=0x84730 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.traceback2(0xb4cfdc54, 0x0, 0x0, 0x31) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:981 +0x88 fp=0xb4cfdbd8 sp=0xb4cfdaa0 pc=0x85fc8 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.traceback1.func1(0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:917 +0x6c fp=0xb4cfdc38 sp=0xb4cfdbd8 pc=0x85e1c Dec 26 12:32:26 volumio go-librespot[1485]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x2002128, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:940 +0x1a4 fp=0xb4cfdd40 sp=0xb4cfdc38 pc=0x85d0c Dec 26 12:32:26 volumio go-librespot[1485]: runtime.traceback(...) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:817 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.tracebackothers.func1(0x2002128) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1259 +0x118 fp=0xb4cfdd5c sp=0xb4cfdd40 pc=0x87714 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.forEachGRace(0xb4cfdd94) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:677 +0x4c fp=0xb4cfdd74 sp=0xb4cfdd5c pc=0x5d378 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.tracebackothers(0x2003448) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/traceback.go:1245 +0xcc fp=0xb4cfdda4 sp=0xb4cfdd74 pc=0x875e4 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.dopanic_m(0x2003448, 0x58e88, 0x22a34a8) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1345 +0x2a8 fp=0xb4cfddd8 sp=0xb4cfdda4 pc=0x59958 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.fatalthrow.func1() Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1199 +0x64 fp=0xb4cfddf8 sp=0xb4cfddd8 pc=0x59388 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.systemstack(0x1000152) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xb4cfddfc sp=0xb4cfddf8 pc=0x91818 Dec 26 12:32:26 volumio go-librespot[1485]: goroutine 35 gp=0x2003448 m=5 mp=0x2080008 [running]: Dec 26 12:32:26 volumio go-librespot[1485]: runtime.systemstack_switch() Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x22a348c sp=0x22a3488 pc=0x917ac Dec 26 12:32:26 volumio go-librespot[1485]: runtime.fatalthrow(0x2) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1192 +0x64 fp=0x22a34a8 sp=0x22a348c pc=0x5930c Dec 26 12:32:26 volumio go-librespot[1485]: runtime.throw({0x665b1a, 0x5}) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:1023 +0x4c fp=0x22a34bc sp=0x22a34a8 pc=0x58e88 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.sigpanic() Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:878 +0x11c fp=0x22a34ec sp=0x22a34bc pc=0x740d0 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*SRV).len(0x2000840, 0x35, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/ztypes.go:678 fp=0x22a34f0 sp=0x22a34f0 pc=0x518918 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.msgLenWithCompressionMap(0x22a3770, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:976 +0x178 fp=0x22a3538 sp=0x22a34f0 pc=0x4eb524 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).packBufferWithCompressionMap(0x22a3770, {0x0, 0x0, 0x0}, {0x0, 0x0}, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:788 +0x198 fp=0x22a35a8 sp=0x22a3538 pc=0x4ea220 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).PackBuffer(0x22a3770, {0x0, 0x0, 0x0}) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:734 +0xf4 fp=0x22a35dc sp=0x22a35a8 pc=0x4ea050 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/miekg/dns.(*Msg).Pack(...) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/miekg/dns@v1.1.54/msg.go:722 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.(*Server).multicastResponse(0x2402400, 0x22a3770, 0x0) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:703 +0x38 fp=0x22a3664 sp=0x22a35dc pc=0x526c2c Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.(*Server).probe(0x2402400) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:543 +0x398 fp=0x22a37e4 sp=0x22a3664 pc=0x525934 Dec 26 12:32:26 volumio go-librespot[1485]: github.com/grandcat/zeroconf.Register.gowrap2() Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x28 fp=0x22a37ec sp=0x22a37e4 pc=0x523708 Dec 26 12:32:26 volumio go-librespot[1485]: runtime.goexit({}) Dec 26 12:32:26 volumio go-librespot[1485]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x22a37ec sp=0x22a37ec pc=0x9346c Dec 26 12:32:26 volumio go-librespot[1485]: created by github.com/grandcat/zeroconf.Register in goroutine 1 Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:79 +0x7d4 Dec 26 12:32:26 volumio go-librespot[1485]: goroutine 1 gp=0x2002128 m=nil [runnable]: Dec 26 12:32:26 volumio go-librespot[1485]: google.golang.org/protobuf/encoding/protowire.ConsumeTag({0xa8205f, 0x47, 0xff}) Dec 26 12:32:26 volumio go-librespot[1485]: /home/runner/go/pkg/mod/google.golang.org/protobuf@v1.30.0/encoding/protowire/wire.go:168 +0xe4 fp=0x214967c sp=0x214967c pc=0x169cb0 Dec 26 12:32:26 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:26 volumio go-librespot[1485]: Bus error Dec 26 12:32:26 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:28 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:28 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:28 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:28 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:29 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:29 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 17. Dec 26 12:32:29 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:29 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:29 volumio go-librespot[1493]: Librespot-go daemon starting... Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=info msg="generated new device id: 87a0c89d032d2d2bd0fde9b7201460320ac87cc1" Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:29 volumio go-librespot[1493]: time="2025-12-26T12:32:29+09:00" level=debug msg="zeroconf server listening on port 42707" Dec 26 12:32:29 volumio go-librespot[1493]: unexpected fault address 0x518918 Dec 26 12:32:29 volumio go-librespot[1493]: fatal error: fault Dec 26 12:32:29 volumio go-librespot[1493]: Bus error Dec 26 12:32:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:31 volumio volumio[885]: info: Initializing connection to go-librespot Websocket Dec 26 12:32:31 volumio volumio[885]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 26 12:32:32 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 26 12:32:32 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 18. Dec 26 12:32:32 volumio systemd[1]: Stopped go-librespot Daemon. Dec 26 12:32:32 volumio systemd[1]: Started go-librespot Daemon. Dec 26 12:32:32 volumio go-librespot[1501]: Librespot-go daemon starting... Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=info msg="generated new device id: 7332f87a9b5cb06382b2e70df0cfd4bbdced8655" Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=debug msg="stored credentials found for 31ipotc76xybp4bgtxnajc6eb4ii" Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 26 12:32:32 volumio go-librespot[1501]: time="2025-12-26T12:32:32+09:00" level=debug msg="zeroconf server listening on port 44981" Dec 26 12:32:32 volumio go-librespot[1501]: unexpected fault address 0x518918Bus error Dec 26 12:32:32 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=135/n/a Dec 26 12:32:32 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 26 12:32:33 volumio sudo[1510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 12:32:33 volumio sudo[1510]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:32:33 volumio sudo[1510]: pam_unix(sudo:session): session closed for user root Dec 26 12:32:33 volumio sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 12:32:33 volumio sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:32:33 volumio sudo[1512]: pam_unix(sudo:session): session closed for user root Dec 26 12:32:33 volumio volumio[885]: verbose: New Socket.io Connection to 10.0.2.9 from 10.0.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 12:32:33 volumio sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 12:32:33 volumio sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:32:33 volumio sudo[1516]: pam_unix(sudo:session): session closed for user root Dec 26 12:32:33 volumio sudo[1519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 12:32:33 volumio sudo[1519]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 12:32:33 volumio sudo[1519]: pam_unix(sudo:session): session closed for user root Dec 26 12:32:33 volumio volumio[885]: verbose: New Socket.io Connection to 10.0.2.9 from 10.0.1.11 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::volumioGetVisibleSources Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:33 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:33 volumio kernel: blk_print_req_error: 43 callbacks suppressed Dec 26 12:32:33 volumio kernel: I/O error, dev loop0, sector 914384 op 0x0:(READ) flags 0x800 phys_seg 29 prio class 2 Dec 26 12:32:33 volumio kernel: SQUASHFS error: Failed to read block 0x1be7a064: -5 Dec 26 12:32:33 volumio kernel: I/O error, dev loop0, sector 914610 op 0x0:(READ) flags 0x800 phys_seg 33 prio class 2 Dec 26 12:32:33 volumio kernel: SQUASHFS error: Failed to read block 0x1be96486: -5 Dec 26 12:32:33 volumio kernel: I/O error, dev loop0, sector 914384 op 0x0:(READ) flags 0x800 phys_seg 29 prio class 2 Dec 26 12:32:33 volumio kernel: SQUASHFS error: Failed to read block 0x1be7a064: -5 Dec 26 12:32:33 volumio kernel: SQUASHFS error: Unable to read data cache entry [1be7a064] Dec 26 12:32:33 volumio kernel: SQUASHFS error: Unable to read page, block 1be7a064, size 1c422 Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 26 12:32:33 volumio volumio[885]: info: Received Get System Info Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 26 12:32:33 volumio volumio[885]: info: Discovery: Getting this device information Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:33 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 26 12:32:33 volumio volumio[885]: info: CoreCommandRouter::volumioGetState Dec 26 12:32:33 volumio volumio[885]: info: CorePlayQueue::getTrack 0 Dec 26 12:32:33 volumio volumio[885]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 12:32:33 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #2390500: lblock 0: comm node: error -5 reading directory block Dec 26 12:32:33 volumio volumio[885]: Error: EIO: i/o error, scandir '/data/plugins/' Dec 26 12:32:33 volumio volumio[885]: at Object.readdirSync (fs.js:1021:3) Dec 26 12:32:33 volumio volumio[885]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Dec 26 12:32:33 volumio volumio[885]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Dec 26 12:32:33 volumio volumio[885]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1328:45) Dec 26 12:32:33 volumio volumio[885]: at Socket.emit (events.js:315:20) Dec 26 12:32:33 volumio volumio[885]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Dec 26 12:32:33 volumio volumio[885]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 26 12:32:33 volumio volumio[885]: errno: -5, Dec 26 12:32:33 volumio volumio[885]: syscall: 'scandir', Dec 26 12:32:33 volumio volumio[885]: code: 'EIO', Dec 26 12:32:33 volumio volumio[885]: path: '/data/plugins/' Dec 26 12:32:33 volumio volumio[885]: } Dec 26 12:32:33 volumio volumio[885]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 12:32:34 volumio sudo[1530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 12:31 Dec 26 12:32:34 volumio sudo[1530]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"