-- Logs begin at Thu 2019-02-14 18:12:00 CST, end at Sat 2024-08-31 18:33:48 CST. -- Aug 31 18:32:29 volumio volumio[12886]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:32:29 volumio volumio[12886]: Error: connect ETIMEDOUT 108.160.165.212:443 Aug 31 18:32:29 volumio volumio[12886]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 31 18:32:29 volumio volumio[12886]: errno: -110, Aug 31 18:32:29 volumio volumio[12886]: code: 'ETIMEDOUT', Aug 31 18:32:29 volumio volumio[12886]: syscall: 'connect', Aug 31 18:32:29 volumio volumio[12886]: address: '108.160.165.212', Aug 31 18:32:29 volumio volumio[12886]: port: 443 Aug 31 18:32:29 volumio volumio[12886]: } Aug 31 18:32:29 volumio volumio[12886]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:32:30 volumio sudo[13263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 18:31 Aug 31 18:32:30 volumio sudo[13263]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:31 volumio sudo[13263]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:31 volumio volumio-remote-updater[440]: [2024-08-31 18:32:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 31 18:32:32 volumio volumio-remote-updater[440]: [2024-08-31 18:32:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 31 18:32:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 31 18:32:32 volumio systemd[1]: media-\xe4\xb8\x8b\xe8\xa7\x86.mount: Succeeded. Aug 31 18:32:32 volumio systemd[1]: media-\xe5\x89\x8d\xe8\xa7\x86.mount: Succeeded. Aug 31 18:32:32 volumio systemd[1]: media-\xe5\x8f\xb3\xe8\xa7\x86.mount: Succeeded. Aug 31 18:32:32 volumio systemd[1]: media-\xe5\x90\x8e\xe8\xa7\x86.mount: Succeeded. Aug 31 18:32:32 volumio systemd[1]: media-\xe5\xb7\xa6\xe8\xa7\x86.mount: Succeeded. Aug 31 18:32:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 31 18:32:32 volumio systemd[1]: Started dynamicswap service. Aug 31 18:32:32 volumio kernel: Aug 31 18:32:32 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 7 Aug 31 18:32:32 volumio kernel: Aug 31 18:32:32 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 6 Aug 31 18:32:32 volumio kernel: Aug 31 18:32:32 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1 Aug 31 18:32:32 volumio kernel: Aug 31 18:32:32 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 5 Aug 31 18:32:32 volumio kernel: Aug 31 18:32:32 volumio kernel: WARN::dwc_otg_hcd_urb_dequeue:639: Timed out waiting for FSM NP transfer to complete on 1 Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 01 00 18 00 00 08 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdb, sector 65560 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: sd 4:0:0:0: [sde] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 4:0:0:0: [sde] tag#0 CDB: opcode=0x28 28 00 00 01 08 00 00 00 08 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sde, sector 67584 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: sd 5:0:0:0: [sdf] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 5:0:0:0: [sdf] tag#0 CDB: opcode=0x28 28 00 00 01 02 08 00 00 f0 00 Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: I/O error, dev sdf, sector 66056 op 0x0:(READ) flags 0x84700 phys_seg 19 prio class 2 Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: sd 5:0:0:0: [sdf] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 5:0:0:0: [sdf] tag#0 CDB: opcode=0x28 28 00 00 01 02 f8 00 00 10 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdf, sector 66296 op 0x0:(READ) flags 0x80700 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port4: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 CDB: opcode=0x28 28 00 1d c9 7c 00 00 00 f0 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdg, sector 499743744 op 0x0:(READ) flags 0x84700 phys_seg 29 prio class 2 Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 CDB: opcode=0x28 28 00 1d c9 7c f0 00 00 10 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdg, sector 499743984 op 0x0:(READ) flags 0x80700 phys_seg 2 prio class 2 Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5-port3: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: sd 2:0:0:0: [sdc] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 2:0:0:0: [sdc] tag#0 CDB: opcode=0x28 28 00 00 01 01 10 00 00 f0 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdc, sector 65808 op 0x0:(READ) flags 0x80700 phys_seg 14 prio class 2 Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot reset (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: Cannot enable. Maybe the USB cable is bad? Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: usb 1-1.5.1-port2: cannot disable (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio kernel: hub 1-1.5.1:1.0: hub_ext_port_status failed (err = -71) Aug 31 18:32:32 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 31 18:32:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 31 18:32:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 31 18:32:32 volumio systemd[1]: Started dynamicswap service. Aug 31 18:32:32 volumio systemd[1]: Stopped Volumio Backend Module. Aug 31 18:32:32 volumio systemd[1]: Started Volumio Backend Module. Aug 31 18:32:32 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 31 18:32:32 volumio kernel: sd 4:0:0:0: [sde] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: sd 4:0:0:0: [sde] tag#0 CDB: opcode=0x28 28 00 00 01 08 00 00 00 08 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sde, sector 67584 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sde1, logical block 256, async page read Aug 31 18:32:32 volumio kernel: I/O error, dev sdf, sector 66056 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdf1, logical block 65, async page read Aug 31 18:32:32 volumio kernel: sd 6:0:0:0: [sdg] tag#0 CDB: opcode=0x28 28 00 1d c9 7c 00 00 00 08 00 Aug 31 18:32:32 volumio kernel: I/O error, dev sdg, sector 499743744 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 2 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdg1, logical block 62459776, async page read Aug 31 18:32:32 volumio kernel: sd 1:0:0:0: [sdb] tag#0 UNKNOWN(0x2003) Result: hostbyte=0x07 driverbyte=DRIVER_OK cmd_age=0s Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdc1, logical block 34, async page read Aug 31 18:32:32 volumio kernel: sd 1:0:0:0: [sdb] tag#0 CDB: opcode=0x28 28 00 00 01 00 18 00 00 08 00 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdb1, logical block 3, async page read Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2800usb_tx_sta_fifo_read_completed: Warning - TX status read failed -71 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdc1, logical block 34, async page read Aug 31 18:32:32 volumio kernel: ieee80211 phy2: rt2x00usb_vendor_request: Error - Vendor Request 0x07 failed for offset 0x1700 with error -71 Aug 31 18:32:32 volumio kernel: usb 1-1-port2: over-current change #5 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdf1, logical block 8288, async page read Aug 31 18:32:32 volumio kernel: sdg: detected capacity change from 499744768 to 0 Aug 31 18:32:32 volumio kernel: sde: detected capacity change from 499744768 to 0 Aug 31 18:32:32 volumio kernel: sdb: detected capacity change from 499744768 to 0 Aug 31 18:32:32 volumio kernel: Buffer I/O error on dev sdc1, logical block 34, async page read Aug 31 18:32:32 volumio kernel: sdd: detected capacity change from 31116288 to 0 Aug 31 18:32:32 volumio systemd-udevd[13298]: Process '/bin/bash -c '/usr/local/bin/volumio usbdetach'' failed with exit code 7. Aug 31 18:32:32 volumio kernel: sdf: detected capacity change from 499744768 to 0 Aug 31 18:32:33 volumio kernel: Buffer I/O error on dev sdc1, logical block 8288, async page read Aug 31 18:32:33 volumio kernel: usb 1-1.3: reset full-speed USB device number 11 using dwc_otg Aug 31 18:32:33 volumio systemd-udevd[13299]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Aug 31 18:32:33 volumio kernel: sdc: detected capacity change from 499744768 to 0 Aug 31 18:32:33 volumio kernel: usb 1-1.5: USB disconnect, device number 12 Aug 31 18:32:33 volumio kernel: usb 1-1.5.1: USB disconnect, device number 13 Aug 31 18:32:33 volumio kernel: usb 1-1.5.1.2: USB disconnect, device number 16 Aug 31 18:32:33 volumio kernel: usb 1-1.5.1.3: USB disconnect, device number 18 Aug 31 18:32:33 volumio kernel: usb 1-1.5.1.4: USB disconnect, device number 20 Aug 31 18:32:34 volumio systemd-udevd[13270]: Process '/usr/sbin/alsactl -E HOME=/run/alsa restore 5' failed with exit code 99. Aug 31 18:32:34 volumio systemd-udevd[13271]: Process '/bin/bash -c '/usr/local/bin/volumio usbattach'' failed with exit code 7. Aug 31 18:32:34 volumio dhcpcd[695]: wlan0: carrier lost Aug 31 18:32:34 volumio kernel: usb 1-1.5.1.5: USB disconnect, device number 21 Aug 31 18:32:34 volumio kernel: usb 1-1.5.2: USB disconnect, device number 14 Aug 31 18:32:34 volumio avahi-daemon[441]: Withdrawing address record for 192.168.1.11 on wlan0. Aug 31 18:32:34 volumio avahi-daemon[441]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.11. Aug 31 18:32:34 volumio dhcpcd[695]: wlan0: deleting route to 192.168.1.0/24 Aug 31 18:32:34 volumio avahi-daemon[441]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 31 18:32:34 volumio dhcpcd[695]: wlan0: deleting default route via 192.168.1.1 Aug 31 18:32:34 volumio systemd-udevd[13298]: Process '/bin/bash -c '/usr/local/bin/volumio scanaudioinputs'' failed with exit code 7. Aug 31 18:32:34 volumio kernel: usb 1-1.5.3: USB disconnect, device number 15 Aug 31 18:32:34 volumio kernel: usb 1-1.5.4: USB disconnect, device number 17 Aug 31 18:32:34 volumio kernel: usb 1-1.5.5: USB disconnect, device number 19 Aug 31 18:32:35 volumio kernel: usb 1-1.5: new high-speed USB device number 22 using dwc_otg Aug 31 18:32:35 volumio kernel: usb 1-1.5: New USB device found, idVendor=2109, idProduct=2822, bcdDevice= 0.30 Aug 31 18:32:35 volumio kernel: usb 1-1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 31 18:32:35 volumio kernel: usb 1-1.5: Product: USB2.0 Hub Aug 31 18:32:35 volumio kernel: usb 1-1.5: Manufacturer: VIA Labs, Inc. Aug 31 18:32:35 volumio kernel: hub 1-1.5:1.0: USB hub found Aug 31 18:32:35 volumio kernel: hub 1-1.5:1.0: 5 ports detected Aug 31 18:32:35 volumio kernel: usb 1-1.2: USB disconnect, device number 10 Aug 31 18:32:35 volumio dhcpcd[695]: wlan0: removing interface Aug 31 18:32:35 volumio dhcpcd-run-hooks[13351]: wlan0: stopping wpa_supplicant Aug 31 18:32:35 volumio systemd[1]: Stopping ifup for wlan0... Aug 31 18:32:35 volumio systemd[1]: Starting Load/Save RF Kill Switch Status... Aug 31 18:32:35 volumio systemd[1]: Started Load/Save RF Kill Switch Status. Aug 31 18:32:36 volumio kernel: usb 1-1.5.1: new high-speed USB device number 23 using dwc_otg Aug 31 18:32:36 volumio systemd[1]: ifup@wlan0.service: Succeeded. Aug 31 18:32:36 volumio systemd[1]: Stopped ifup for wlan0. Aug 31 18:32:36 volumio kernel: usb 1-1.5.1: New USB device found, idVendor=2109, idProduct=2822, bcdDevice= 0.30 Aug 31 18:32:36 volumio kernel: usb 1-1.5.1: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Aug 31 18:32:36 volumio kernel: usb 1-1.5.1: Product: USB2.0 Hub Aug 31 18:32:36 volumio kernel: usb 1-1.5.1: Manufacturer: VIA Labs, Inc. Aug 31 18:32:36 volumio kernel: hub 1-1.5.1:1.0: USB hub found Aug 31 18:32:36 volumio kernel: hub 1-1.5.1:1.0: 5 ports detected Aug 31 18:32:36 volumio kernel: usb 1-1.2: new high-speed USB device number 24 using dwc_otg Aug 31 18:32:36 volumio kernel: usb 1-1.2: New USB device found, idVendor=148f, idProduct=5370, bcdDevice= 1.01 Aug 31 18:32:36 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 18:32:36 volumio kernel: usb 1-1.2: Product: 802.11 n WLAN Aug 31 18:32:36 volumio kernel: usb 1-1.2: Manufacturer: Ralink Aug 31 18:32:36 volumio kernel: usb 1-1.2: SerialNumber: 1.0 Aug 31 18:32:36 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:36 volumio volumio[13282]: info: ----- Volumio3 ---- Aug 31 18:32:36 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:36 volumio volumio[13282]: info: ----- System startup ---- Aug 31 18:32:36 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: new high-speed USB device number 25 using dwc_otg Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: Product: USB3.0 Card Reader Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: Manufacturer: Generic Aug 31 18:32:36 volumio kernel: usb 1-1.5.2: SerialNumber: 000000001532 Aug 31 18:32:36 volumio kernel: usb-storage 1-1.5.2:1.0: USB Mass Storage device detected Aug 31 18:32:36 volumio kernel: scsi host1: usb-storage 1-1.5.2:1.0 Aug 31 18:32:36 volumio ntpd[593]: Deleting interface #5 wlan0, 192.168.1.11#123, interface stats: received=78, sent=84, dropped=0, active_time=534 secs Aug 31 18:32:36 volumio ntpd[593]: 43.136.79.196 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 202.112.31.197 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 119.28.183.184 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 211.68.71.118 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 162.159.200.1 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 162.159.200.123 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 202.118.1.130 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 139.199.214.202 local addr 192.168.1.11 -> Aug 31 18:32:36 volumio ntpd[593]: 78.46.102.180 local addr 192.168.1.11 -> Aug 31 18:32:37 volumio volumio-remote-updater[440]: [2024-08-31 18:32:37] [connect] Successful connection Aug 31 18:32:37 volumio kernel: usb 1-1.2: reset high-speed USB device number 24 using dwc_otg Aug 31 18:32:37 volumio kernel: ieee80211 phy3: rt2x00_set_rt: Info - RT chipset 5390, rev 0502 detected Aug 31 18:32:37 volumio kernel: ieee80211 phy3: rt2x00_set_rf: Info - RF chipset 5370 detected Aug 31 18:32:37 volumio kernel: ieee80211 phy3: Selected rate control algorithm 'minstrel_ht' Aug 31 18:32:37 volumio systemd-udevd[13299]: Using default interface naming scheme 'v240'. Aug 31 18:32:37 volumio systemd-udevd[13299]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: new high-speed USB device number 26 using dwc_otg Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: Product: USB3.0 Card Reader Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: Manufacturer: Generic Aug 31 18:32:37 volumio kernel: usb 1-1.5.3: SerialNumber: 000000001532 Aug 31 18:32:37 volumio kernel: usb-storage 1-1.5.3:1.0: USB Mass Storage device detected Aug 31 18:32:37 volumio systemd[1]: Found device RT5370 Wireless Adapter. Aug 31 18:32:37 volumio kernel: scsi host2: usb-storage 1-1.5.3:1.0 Aug 31 18:32:37 volumio dhcpcd-run-hooks[13417]: wlan0: starting wpa_supplicant Aug 31 18:32:37 volumio kernel: ieee80211 phy3: rt2x00lib_request_firmware: Info - Loading firmware file 'rt2870.bin' Aug 31 18:32:37 volumio kernel: ieee80211 phy3: rt2x00lib_request_firmware: Info - Firmware detected - version: 0.36 Aug 31 18:32:37 volumio systemd[1]: Started ifup for wlan0. Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: new high-speed USB device number 27 using dwc_otg Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: Product: USB3.0 Card Reader Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: Manufacturer: Generic Aug 31 18:32:37 volumio kernel: usb 1-1.5.1.2: SerialNumber: 000000001532 Aug 31 18:32:37 volumio kernel: usb-storage 1-1.5.1.2:1.0: USB Mass Storage device detected Aug 31 18:32:37 volumio kernel: scsi host4: usb-storage 1-1.5.1.2:1.0 Aug 31 18:32:37 volumio kernel: usb 1-1.5.4: new high-speed USB device number 28 using dwc_otg Aug 31 18:32:37 volumio kernel: scsi 1:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:37 volumio kernel: sd 1:0:0:0: Attached scsi generic sg0 type 0 Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: connected to Access Point `' Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: waiting for carrier Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: carrier acquired Aug 31 18:32:38 volumio kernel: usb 1-1.5.4: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:38 volumio kernel: usb 1-1.5.4: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:38 volumio kernel: usb 1-1.5.4: Product: USB3.0 Card Reader Aug 31 18:32:38 volumio kernel: usb 1-1.5.4: Manufacturer: Generic Aug 31 18:32:38 volumio kernel: usb 1-1.5.4: SerialNumber: 000000001532 Aug 31 18:32:38 volumio kernel: usb-storage 1-1.5.4:1.0: USB Mass Storage device detected Aug 31 18:32:38 volumio kernel: scsi host5: usb-storage 1-1.5.4:1.0 Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: IAID 54:01:17:7e Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: adding address fe80::eade:accb:9adf:a65f Aug 31 18:32:38 volumio dhcpcd[695]: ipv6_addaddr1: Permission denied Aug 31 18:32:38 volumio dhcpcd[695]: wlan0: carrier lost Aug 31 18:32:38 volumio volumio[13282]: info: MYVOLUMIO Environment detected Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: new high-speed USB device number 29 using dwc_otg Aug 31 18:32:38 volumio sh[13418]: wlan0=wlan0 Aug 31 18:32:38 volumio kernel: sd 1:0:0:0: [sdb] 499744768 512-byte logical blocks: (256 GB/238 GiB) Aug 31 18:32:38 volumio kernel: sd 1:0:0:0: [sdb] Write Protect is off Aug 31 18:32:38 volumio kernel: sd 1:0:0:0: [sdb] Mode Sense: 21 00 00 00 Aug 31 18:32:38 volumio kernel: sd 1:0:0:0: [sdb] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:38 volumio kernel: sdb: sdb1 Aug 31 18:32:38 volumio kernel: sd 1:0:0:0: [sdb] Attached SCSI removable disk Aug 31 18:32:38 volumio volumio[13282]: info: Plugin folders cleanup Aug 31 18:32:38 volumio volumio[13282]: info: Scanning into folder /volumio/app/plugins/ Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category audio_interface Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category miscellanea Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category music_service Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category plugins.json Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category system_controller Aug 31 18:32:38 volumio volumio[13282]: info: Scanning category user_interface Aug 31 18:32:38 volumio volumio[13282]: info: Scanning into folder /data/plugins/ Aug 31 18:32:38 volumio volumio[13282]: info: Plugin folders cleanup completed Aug 31 18:32:38 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:38 volumio volumio[13282]: info: ----- Core plugins startup ---- Aug 31 18:32:38 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:38 volumio volumio[13282]: info: Loading plugins from folder /volumio/app/plugins/ Aug 31 18:32:38 volumio volumio[13282]: info: Adding plugin upnp to MyMusic Plugins Aug 31 18:32:38 volumio volumio[13282]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 31 18:32:38 volumio volumio[13282]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: Product: USB3.0 Card Reader Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: Manufacturer: Generic Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.3: SerialNumber: 000000001532 Aug 31 18:32:38 volumio kernel: usb-storage 1-1.5.1.3:1.0: USB Mass Storage device detected Aug 31 18:32:38 volumio kernel: scsi host6: usb-storage 1-1.5.1.3:1.0 Aug 31 18:32:38 volumio volumio[13282]: info: Loading plugins from folder /data/plugins/ Aug 31 18:32:38 volumio volumio[13282]: info: Loading plugin "system"... Aug 31 18:32:38 volumio kernel: scsi 2:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: Attached scsi generic sg1 type 0 Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: new high-speed USB device number 30 using dwc_otg Aug 31 18:32:38 volumio volumio[13282]: info: Loading plugin "appearance"... Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: New USB device found, idVendor=2109, idProduct=8822, bcdDevice= 0.01 Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: Product: USB Billboard Device Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: Manufacturer: VIA Labs, Inc. Aug 31 18:32:38 volumio kernel: usb 1-1.5.5: SerialNumber: 0000000000000001 Aug 31 18:32:38 volumio kernel: scsi 4:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:38 volumio kernel: sd 4:0:0:0: Attached scsi generic sg2 type 0 Aug 31 18:32:38 volumio kernel: usb 1-1.5.1.4: new high-speed USB device number 31 using dwc_otg Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: [sdc] 499744768 512-byte logical blocks: (256 GB/238 GiB) Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: [sdc] Write Protect is off Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: [sdc] Mode Sense: 21 00 00 00 Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: [sdc] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:38 volumio kernel: sdc: sdc1 Aug 31 18:32:38 volumio kernel: sd 2:0:0:0: [sdc] Attached SCSI removable disk Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.4: New USB device found, idVendor=05e3, idProduct=0749, bcdDevice=15.32 Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.4: New USB device strings: Mfr=3, Product=4, SerialNumber=2 Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.4: Product: USB3.0 Card Reader Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.4: Manufacturer: Generic Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.4: SerialNumber: 000000001532 Aug 31 18:32:39 volumio kernel: usb-storage 1-1.5.1.4:1.0: USB Mass Storage device detected Aug 31 18:32:39 volumio kernel: scsi host7: usb-storage 1-1.5.1.4:1.0 Aug 31 18:32:39 volumio kernel: sd 4:0:0:0: [sde] 31116288 512-byte logical blocks: (15.9 GB/14.8 GiB) Aug 31 18:32:39 volumio kernel: sd 4:0:0:0: [sde] Write Protect is off Aug 31 18:32:39 volumio kernel: sd 4:0:0:0: [sde] Mode Sense: 21 00 00 00 Aug 31 18:32:39 volumio kernel: sd 4:0:0:0: [sde] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: new high-speed USB device number 32 using dwc_otg Aug 31 18:32:39 volumio kernel: sde: sde1 Aug 31 18:32:39 volumio kernel: sd 4:0:0:0: [sde] Attached SCSI removable disk Aug 31 18:32:39 volumio kernel: scsi 5:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: Attached scsi generic sg3 type 0 Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: New USB device found, idVendor=2109, idProduct=8822, bcdDevice= 0.01 Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: Product: USB Billboard Device Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: Manufacturer: VIA Labs, Inc. Aug 31 18:32:39 volumio kernel: usb 1-1.5.1.5: SerialNumber: 0000000000000001 Aug 31 18:32:39 volumio kernel: wlan0: authenticate with 34:fc:a1:9d:21:a3 Aug 31 18:32:39 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Aug 31 18:32:39 volumio kernel: wlan0: send auth to 34:fc:a1:9d:21:a3 (try 1/3) Aug 31 18:32:39 volumio kernel: wlan0: authenticated Aug 31 18:32:39 volumio kernel: wlan0: associate with 34:fc:a1:9d:21:a3 (try 1/3) Aug 31 18:32:39 volumio kernel: scsi 6:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: Attached scsi generic sg4 type 0 Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: [sdf] 499744768 512-byte logical blocks: (256 GB/238 GiB) Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: [sdf] Write Protect is off Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: [sdf] Mode Sense: 21 00 00 00 Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: [sdf] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:39 volumio kernel: sdf: sdf1 Aug 31 18:32:39 volumio kernel: sd 5:0:0:0: [sdf] Attached SCSI removable disk Aug 31 18:32:39 volumio kernel: wlan0: associate with 34:fc:a1:9d:21:a3 (try 2/3) Aug 31 18:32:39 volumio kernel: wlan0: RX AssocResp from 34:fc:a1:9d:21:a3 (capab=0x1411 status=0 aid=1) Aug 31 18:32:39 volumio kernel: wlan0: associated Aug 31 18:32:39 volumio dhcpcd[695]: wlan0: carrier acquired Aug 31 18:32:39 volumio dhcpcd[695]: wlan0: connected to Access Point `CMCC-2KAG' Aug 31 18:32:39 volumio dhcpcd[695]: wlan0: IAID 54:01:17:7e Aug 31 18:32:39 volumio kernel: wlan0: Limiting TX power to 30 (30 - 0) dBm as advertised by 34:fc:a1:9d:21:a3 Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: [sdg] 499744768 512-byte logical blocks: (256 GB/238 GiB) Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: [sdg] Write Protect is off Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: [sdg] Mode Sense: 21 00 00 00 Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: [sdg] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:39 volumio kernel: sdg: sdg1 Aug 31 18:32:39 volumio kernel: sd 6:0:0:0: [sdg] Attached SCSI removable disk Aug 31 18:32:39 volumio dhcpcd[695]: wlan0: rebinding lease of 192.168.1.11 Aug 31 18:32:40 volumio dhcpcd[695]: wlan0: probing address 192.168.1.11/24 Aug 31 18:32:40 volumio dhcpcd[695]: wlan0: soliciting an IPv6 router Aug 31 18:32:40 volumio kernel: scsi 7:0:0:0: Direct-Access Generic STORAGE DEVICE 1532 PQ: 0 ANSI: 6 Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: Attached scsi generic sg5 type 0 Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: [sdh] 499744768 512-byte logical blocks: (256 GB/238 GiB) Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: [sdh] Write Protect is off Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: [sdh] Mode Sense: 21 00 00 00 Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: [sdh] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA Aug 31 18:32:40 volumio kernel: sdh: sdh1 Aug 31 18:32:40 volumio kernel: sd 7:0:0:0: [sdh] Attached SCSI removable disk Aug 31 18:32:40 volumio volumio[13282]: info: Loading plugin "network"... Aug 31 18:32:40 volumio volumio[13282]: info: Refreshing Cached IP Addresses Aug 31 18:32:40 volumio sudo[13501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 18:32:40 volumio sudo[13501]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:40 volumio volumio[13282]: info: Loading plugin "services"... Aug 31 18:32:40 volumio sudo[13501]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:40 volumio sudo[13503]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 18:32:40 volumio sudo[13503]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:40 volumio volumio[13282]: info: Loading plugin "alsa_controller"... Aug 31 18:32:40 volumio sudo[13503]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:40 volumio sudo[13506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 31 18:32:40 volumio sudo[13506]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:41 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:32:41 volumio volumio[13282]: info: Loading plugin "wizard"... Aug 31 18:32:41 volumio volumio[13282]: info: Loading plugin "networkfs"... Aug 31 18:32:41 volumio volumio[13282]: info: Starting Udev Watcher for removable devices Aug 31 18:32:41 volumio volumio[13282]: info: Ignoring mount for partition: boot Aug 31 18:32:41 volumio volumio[13282]: info: Ignoring mount for partition: volumio Aug 31 18:32:41 volumio volumio[13282]: info: Ignoring mount for partition: volumio_data Aug 31 18:32:41 volumio volumio[13282]: info: Mounting Device POS Aug 31 18:32:41 volumio sudo[13517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sde1 /mnt/USB/POS -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 31 18:32:41 volumio sudo[13517]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:41 volumio kernel: FAT-fs (sde1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! Aug 31 18:32:41 volumio kernel: FAT-fs (sde1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. Aug 31 18:32:41 volumio sudo[13517]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:41 volumio volumio[13282]: info: Mounting Device 左视 Aug 31 18:32:41 volumio sudo[13523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdg1 /mnt/USB/左视 -o noatime Aug 31 18:32:41 volumio sudo[13523]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:41 volumio sudo[13523]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:41 volumio volumio[13282]: info: Mounting Device 后视 Aug 31 18:32:41 volumio sudo[13531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdh1 /mnt/USB/后视 -o noatime Aug 31 18:32:41 volumio sudo[13531]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:42 volumio sudo[13531]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:42 volumio volumio[13282]: info: Mounting Device 右视 Aug 31 18:32:42 volumio systemd[1]: systemd-rfkill.service: Succeeded. Aug 31 18:32:42 volumio sudo[13539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/右视 -o noatime Aug 31 18:32:42 volumio sudo[13539]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:42 volumio sudo[13539]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:42 volumio volumio[13282]: info: Mounting Device 前视 Aug 31 18:32:42 volumio sudo[13547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdc1 /mnt/USB/前视 -o noatime Aug 31 18:32:42 volumio sudo[13547]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:42 volumio sudo[13547]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:42 volumio volumio[13282]: info: Mounting Device 下视 Aug 31 18:32:43 volumio sudo[13555]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdf1 /mnt/USB/下视 -o noatime Aug 31 18:32:43 volumio sudo[13555]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:43 volumio sudo[13555]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:43 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:32:43 volumio volumio[13282]: info: Loading plugin "volumio_command_line_client"... Aug 31 18:32:43 volumio volumio[13282]: info: Loading plugin "upnp"... Aug 31 18:32:43 volumio volumio[13282]: info: [1725100363239] Starting Upmpd Daemon Aug 31 18:32:43 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:32:43 volumio volumio[13282]: info: Loading plugin "my_music"... Aug 31 18:32:43 volumio volumio[13282]: info: Loading plugin "mpd"... Aug 31 18:32:43 volumio volumio[13282]: info: Loading plugin "upnp_browser"... Aug 31 18:32:44 volumio volumio[13282]: info: Loading plugin "alarm-clock"... Aug 31 18:32:44 volumio dhcpcd[695]: wlan0: leased 192.168.1.11 for 86400 seconds Aug 31 18:32:44 volumio dhcpcd[695]: wlan0: adding route to 192.168.1.0/24 Aug 31 18:32:44 volumio dhcpcd[695]: wlan0: adding default route via 192.168.1.1 Aug 31 18:32:44 volumio avahi-daemon[441]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.11. Aug 31 18:32:44 volumio avahi-daemon[441]: New relevant interface wlan0.IPv4 for mDNS. Aug 31 18:32:44 volumio avahi-daemon[441]: Registering new address record for 192.168.1.11 on wlan0.IPv4. Aug 31 18:32:44 volumio volumio[13282]: info: Loading plugin "airplay_emulation"... Aug 31 18:32:44 volumio volumio[13282]: info: Starting Shairport Sync Aug 31 18:32:44 volumio volumio[13282]: info: Loading plugin "last_100"... Aug 31 18:32:44 volumio volumio[13282]: info: Loading plugin "webradio"... Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "i2s_dacs"... Aug 31 18:32:45 volumio volumio[13282]: info: I2S DAC not set, start Auto-detection Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "volumiodiscovery"... Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 18:32:45 volumio node[13282]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:32:45 volumio node[13282]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** For more information see Aug 31 18:32:45 volumio node[13282]: *** WARNING *** For more information see Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 18:32:45 volumio node[13282]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:32:45 volumio node[13282]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 31 18:32:45 volumio volumio[13282]: *** WARNING *** For more information see Aug 31 18:32:45 volumio node[13282]: *** WARNING *** For more information see Aug 31 18:32:45 volumio volumio[13282]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 31 18:32:45 volumio volumio[13282]: info: Discovery: Started advertising with name: Volumio Aug 31 18:32:45 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "outputs"... Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "albumart"... Aug 31 18:32:45 volumio volumio[13282]: info: Plugin example_plugin is not enabled Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "inputs"... Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "updater_comm"... Aug 31 18:32:45 volumio volumio[13282]: info: Plugin mpdemulation is not enabled Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "rest_api"... Aug 31 18:32:45 volumio volumio[13282]: info: Loading plugin "websocket"... Aug 31 18:32:45 volumio volumio[13282]: info: Loading i18n strings for locale zh Aug 31 18:32:45 volumio volumio[13282]: Updating browse sources language Aug 31 18:32:45 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:32:45 volumio ntpd[593]: Listen normally on 6 wlan0 192.168.1.11:123 Aug 31 18:32:45 volumio ntpd[593]: new interface(s) found: waking up resolver Aug 31 18:32:46 volumio sudo[13506]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:46 volumio volumio[13282]: Forking 3 albumart workers Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::initPlayerControls Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:32:46 volumio volumio[13282]: Express server listening on port 3000 Aug 31 18:32:46 volumio volumio[13282]: [Metrics] WebUI: 10s 885.94ms Aug 31 18:32:46 volumio volumio[13282]: info: CoreStateMachine::resetVolumioState Aug 31 18:32:46 volumio volumio[13282]: info: CoreStateMachine::getcurrentVolume Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 18:32:46 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:47 volumio volumio[13282]: info: Scanning new location : "USB/POS" Aug 31 18:32:47 volumio volumio[13282]: info: Scanning new location : "USB/左视" Aug 31 18:32:47 volumio volumio[13282]: info: Scanning new location : "USB/右视" Aug 31 18:32:47 volumio volumio[13282]: info: Reloading queue from file Aug 31 18:32:47 volumio volumio[13282]: info: Setting Device type: Raspberry PI Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::setRepeat true single undefined Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::pushState Aug 31 18:32:47 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::volumioPushState Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::setRandom false Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::pushState Aug 31 18:32:47 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::volumioPushState Aug 31 18:32:47 volumio volumio[13282]: info: VolumeController:: Volume=35 Mute =false Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::pushState Aug 31 18:32:47 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::volumioPushState Aug 31 18:32:47 volumio volumio[13282]: info: CoreStateMachine::updateTrackBlock Aug 31 18:32:47 volumio volumio[13282]: info: CorePlayQueue::getTrackBlock Aug 31 18:32:47 volumio volumio[13282]: info: CoreCommandRouter::volumioRetrievevolume Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/LINK/20ee3822-ab52-4bda-8740-943ee5b856d0.jpg' Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Beyond/%E9%BB%84%E5%AE%B6%E9%A9%B9%E5%8E%9F%E4%BD%9C%E7%B2%BE%E9%80%89%E9%9B%86/9699df7c-ed96-48a0-81b3-b26e3ed81e1a.jpg' Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BY2/90%27%E9%97%B9Now/2d11b15c-4567-411f-b616-b069e532b419.jpg' Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BY2/16%E6%9C%AA%E6%88%90%E5%B9%B4/776d9158-8c31-465f-ac4c-3780ac89782a.png' Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/By2/04d17682-4099-4400-9c21-c4c9b7e4989f.jpg' Aug 31 18:32:48 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/BY2/2020%E7%88%B1%E4%BD%A0%E7%88%B1%E4%BD%A0/b0e7710e-7914-4ba3-9d39-fbdbed4f4c10.png' Aug 31 18:32:48 volumio volumio[13282]: Starting albumart workers Aug 31 18:32:48 volumio volumio[13282]: info: Completed loading Core Plugins Aug 31 18:32:48 volumio volumio[13282]: info: Preparing to generate the ALSA configuration file Aug 31 18:32:48 volumio volumio[13282]: Starting albumart workers Aug 31 18:32:48 volumio volumio[13282]: info: VolumeController:: Volume=35 Mute =false Aug 31 18:32:48 volumio volumio[13282]: info: CoreStateMachine::pushState Aug 31 18:32:48 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:32:48 volumio volumio[13282]: info: CoreCommandRouter::volumioPushState Aug 31 18:32:48 volumio volumio[13282]: info: Asound.conf file unchanged, so no further update is needed Aug 31 18:32:48 volumio volumio[13282]: info: Output device has changed, restarting MPD Aug 31 18:32:48 volumio volumio[13282]: info: Output device has changed, restarting Shairport Sync Aug 31 18:32:48 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:48 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:48 volumio sudo[13634]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 18:32:48 volumio sudo[13632]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 18:32:48 volumio sudo[13634]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:48 volumio sudo[13632]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:48 volumio sudo[13632]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:48 volumio systemd[1]: Stopping Music Player Daemon... Aug 31 18:32:49 volumio volumio[13282]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:32:49 volumio systemd[1]: mpd.service: Succeeded. Aug 31 18:32:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 18:32:49 volumio volumio[13282]: info: ___________ START PLUGINS ___________ Aug 31 18:32:49 volumio volumio[13282]: info: ControllerMpd::onStart: Initializing MPD Aug 31 18:32:49 volumio volumio[13282]: info: Creating MPD Configuration file Aug 31 18:32:49 volumio systemd[1]: Starting Music Player Daemon... Aug 31 18:32:49 volumio volumio[13282]: Starting albumart workers Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:32:49 volumio volumio[13282]: info: [1725100369215] CoreMusicLibrary::Adding element 媒体服务器 Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:32:49 volumio sudo[13643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 31 18:32:49 volumio sudo[13643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:49 volumio sudo[13643]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:49 volumio sudo[13645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 31 18:32:49 volumio sudo[13645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:49 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Aug 31 18:32:49 volumio systemd[1]: mpd.service: Succeeded. Aug 31 18:32:49 volumio systemd[1]: Stopped Music Player Daemon. Aug 31 18:32:49 volumio systemd[1]: Starting Music Player Daemon... Aug 31 18:32:49 volumio volumio-remote-updater[440]: [2024-08-31 18:32:49] [connect] Successful connection Aug 31 18:32:49 volumio volumio[13282]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:49 volumio volumio[13282]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:32:49 volumio volumio[13282]: info: [1725100369700] CoreMusicLibrary::Adding element Last_100 Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 31 18:32:49 volumio volumio[13282]: info: [1725100369710] CoreMusicLibrary::Adding element Webradio Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 31 18:32:49 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:32:49 volumio volumio[13282]: info: Volumio Calling Home Aug 31 18:32:50 volumio volumio[13282]: info: MPD Permissions set Aug 31 18:32:50 volumio volumio[13282]: info: MPD Permissions set Aug 31 18:32:50 volumio volumio-remote-updater[440]: [2024-08-31 18:32:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725100369 101 Aug 31 18:32:50 volumio volumio[13282]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Aug 31 18:32:50 volumio volumio[13282]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:32:50 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:32:51 volumio volumio[13282]: info: Starting Shairport Sync Aug 31 18:32:51 volumio volumio[13282]: info: Starting Shairport Sync Aug 31 18:32:51 volumio sudo[13691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:32:51 volumio sudo[13691]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:51 volumio volumio[13282]: info: Starting Shairport Sync Aug 31 18:32:51 volumio sudo[13694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:32:51 volumio sudo[13694]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 18:32:51 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 18:32:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 18:32:51 volumio sudo[13696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 31 18:32:51 volumio sudo[13696]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 18:32:51 volumio sudo[13691]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:51 volumio volumio[13282]: info: Shairport-Sync Started Aug 31 18:32:51 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 31 18:32:51 volumio volumio[13282]: Error adding Membership: Error: addMembership EINVAL Aug 31 18:32:51 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Aug 31 18:32:51 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 31 18:32:51 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 31 18:32:51 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 31 18:32:51 volumio sudo[13694]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:51 volumio sudo[13696]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:51 volumio volumio[13282]: info: CoreCommandRouter::volumioGetState Aug 31 18:32:51 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:32:51 volumio volumio[13282]: info: Shairport-Sync Started Aug 31 18:32:51 volumio volumio[13282]: info: Shairport-Sync Started Aug 31 18:32:52 volumio volumio[13282]: info: Volumio called home Aug 31 18:32:52 volumio mpd[13648]: Aug 31 18:32 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 31 18:32:52 volumio systemd[1]: Started Music Player Daemon. Aug 31 18:32:52 volumio sudo[13634]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:52 volumio sudo[13645]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:53 volumio volumio[13282]: info: Completed starting Core Plugins Aug 31 18:32:53 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:53 volumio volumio[13282]: info: ----- MyVolumio plugins startup ---- Aug 31 18:32:53 volumio volumio[13282]: info: ------------------------------------------- Aug 31 18:32:53 volumio volumio[13282]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 31 18:32:53 volumio volumio[13282]: error: MPD error: The expression evaluated to a falsy value: Aug 31 18:32:53 volumio volumio[13282]: assert.ok(self.idling) Aug 31 18:32:53 volumio volumio[13282]: error: The expression evaluated to a falsy value: Aug 31 18:32:53 volumio volumio[13282]: assert.ok(self.idling) Aug 31 18:32:53 volumio volumio[13282]: info: MPD running with PID13648 Aug 31 18:32:53 volumio volumio[13282]: ,establishing connection Aug 31 18:32:53 volumio volumio[13282]: error: updateQueue error: null Aug 31 18:32:53 volumio volumio[13282]: error: updateQueue error: null Aug 31 18:32:53 volumio sudo[13710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 18:32:53 volumio sudo[13710]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:53 volumio sudo[13710]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:53 volumio sudo[13712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 18:32:53 volumio sudo[13712]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:53 volumio sudo[13712]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:53 volumio sudo[13715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Aug 31 18:32:53 volumio sudo[13715]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:32:53 volumio sudo[13715]: pam_unix(sudo:session): session closed for user root Aug 31 18:32:53 volumio volumio[13282]: info: Upmpdcli Daemon Started Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd4.f59d64e6 does not match aorg 0xea7d6fd8.f599bc6d from server@43.136.79.196 xmt 0xea7d6fd5.2b29b3f8 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd4.f5b9c238 does not match aorg 0xea7d6fd8.f5a9597f from server@202.118.1.130 xmt 0xea7d6fd5.317c16d6 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd4.f5b1322e does not match aorg 0xea7d6fd8.f5a3b9d7 from server@78.46.102.180 xmt 0xea7d6fd5.44da8810 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd5.f5b4e138 does not match aorg 0xea7d6fd9.f5b32c83 from server@119.28.183.184 xmt 0xea7d6fd6.42903e23 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd5.f5b0605d does not match aorg 0xea7d6fd9.f5aeca2b from server@211.68.71.118 xmt 0xea7d6fd6.4628dee6 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd5.f59b34f5 does not match aorg 0xea7d6fd9.f59a278d from server@202.112.31.197 xmt 0xea7d6fd6.47ed7906 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd5.f5a671c5 does not match aorg 0xea7d6fd9.f5a3c166 from server@162.159.200.123 xmt 0xea7d6fd6.55d38432 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd5.f5abc396 does not match aorg 0xea7d6fd9.f5a9294f from server@162.159.200.1 xmt 0xea7d6fd6.56773684 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd6.f59a7cc5 does not match aorg 0xea7d6fd8.f599bc6d from server@43.136.79.196 xmt 0xea7d6fd7.a271e834 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd6.f5ac3939 does not match aorg 0xea7d6fd8.f5a9597f from server@202.118.1.130 xmt 0xea7d6fd7.a9c9bbb2 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd7.f5b580a4 does not match aorg 0xea7d6fd9.f5b32c83 from server@119.28.183.184 xmt 0xea7d6fd8.736cc2f4 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd7.f5b111e9 does not match aorg 0xea7d6fd9.f5aeca2b from server@211.68.71.118 xmt 0xea7d6fd8.7711eb62 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd7.f59b2392 does not match aorg 0xea7d6fd9.f59a278d from server@202.112.31.197 xmt 0xea7d6fd8.78e4efd4 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd7.f5a71c87 does not match aorg 0xea7d6fd9.f5a3c166 from server@162.159.200.123 xmt 0xea7d6fd8.86d892c8 Aug 31 18:32:58 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d6fd7.f5ac6c0c does not match aorg 0xea7d6fd9.f5a9294f from server@162.159.200.1 xmt 0xea7d6fd8.876ea794 Aug 31 18:32:58 volumio volumio[13282]: info: Discovery: adding 873373ba-3225-4bcf-aa8f-76c4e7de2dc7 Aug 31 18:32:58 volumio volumio[13282]: info: Discovery: Found device Volumio Aug 31 18:32:58 volumio volumio[13282]: info: CoreCommandRouter::volumioGetState Aug 31 18:32:58 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:33:05 volumio sudo[13719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 31 18:33:05 volumio sudo[13719]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:33:05 volumio sudo[13719]: pam_unix(sudo:session): session closed for user root Aug 31 18:33:05 volumio sudo[13721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 31 18:33:05 volumio sudo[13721]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:33:05 volumio sudo[13721]: pam_unix(sudo:session): session closed for user root Aug 31 18:33:05 volumio volumio[13282]: verbose: New Socket.io Connection to 192.168.1.11 from 192.168.1.6 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/120.0.0.0 Safari/537.36 Total Clients: 3 Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin bluetooth to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin multiroom to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin metavolumio to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin cd_controller to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 31 18:33:05 volumio volumio[13282]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 31 18:33:07 volumio volumio[13282]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 31 18:33:07 volumio volumio[13282]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 31 18:33:07 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:33:07 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:33:07 volumio volumio[13282]: info: Starting MyVolumio Remote Streaming Endpoints Aug 31 18:33:07 volumio volumio[13282]: info: MyVolumio not started Aug 31 18:33:07 volumio volumio[13282]: info: Initializing device activation check Aug 31 18:33:07 volumio volumio[13282]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 31 18:33:07 volumio volumio[13282]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 31 18:33:09 volumio volumio[13282]: info: Starting Streaming Service Transparent Proxy Aug 31 18:33:09 volumio volumio[13282]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 31 18:33:09 volumio volumio[13282]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 31 18:33:09 volumio volumio[13282]: info: Streaming services startup Aug 31 18:33:09 volumio volumio[13282]: info: Starting Streaming Daemon Aug 31 18:33:09 volumio sudo[13732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 31 18:33:09 volumio sudo[13732]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 31 18:33:09 volumio volumio[13282]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 31 18:33:09 volumio sudo[13732]: pam_unix(sudo:session): session closed for user root Aug 31 18:33:10 volumio volumio[13282]: error: Cannot start Volumio Streaming Daemon Aug 31 18:33:10 volumio volumio[13282]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 31 18:33:10 volumio volumio[13282]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 31 18:33:10 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E5%A4%B1%E6%81%8B%E6%97%A0%E7%BD%AA/973edbcb-64a1-4e97-8f9b-ad7a2d203416.jpg' Aug 31 18:33:10 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E6%88%91%E6%98%AF%E6%AD%8C%E6%89%8B%E7%AC%AC%E4%B8%89%E5%AD%A3%20%E7%AC%AC4%E6%9C%9F/af3da0b6-2abd-43a0-bd15-dc22fa16946b.jpg' Aug 31 18:33:10 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/LINK/20ee3822-ab52-4bda-8740-943ee5b856d0.jpg' Aug 31 18:33:10 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E5%AF%82%E5%AF%9E%E4%B8%8D%E7%97%9B/2af44e10-d979-443d-ab37-cf6496188a1d.png' Aug 31 18:33:12 volumio volumio[13282]: STREAMING PROXY: Starting server on port 3245 Aug 31 18:33:12 volumio volumio[13282]: Node JS runtime: 14 Aug 31 18:33:16 volumio ntpd[593]: Soliciting pool server 2606:4700:f1::1 Aug 31 18:33:19 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:33:19 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:33:19 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 31 18:33:21 volumio volumio-remote-updater[440]: No test mode Aug 31 18:33:21 volumio volumio-remote-updater[440]: No alpha test mode Aug 31 18:33:21 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 31 18:33:22 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 31 18:33:22 volumio volumio[13282]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Aug 31 18:33:22 volumio volumio[13282]: info: CoreCommandRouter::volumioGetState Aug 31 18:33:22 volumio volumio[13282]: info: CorePlayQueue::getTrack 0 Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::volumioGetBrowseSources Aug 31 18:33:27 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 31 18:33:28 volumio volumio[13282]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

    • Improved translations
    • Fix for controlling Volumio device from another one
    • Fix for slow playback on RPi5 with HifiBerry DAC
    • Improved speed for default albumart
    • Fix for HifiBerry DAC detection
    • Fix for Supersearch bar on Iphone
    • Fix for Network connection on Wizard



NEW ADDITIONS

    • Facility for third party UIs

 

","title":"Update v3.742","updateavailable":true} Aug 31 18:33:28 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 31 18:33:29 volumio volumio[13282]: error: MyVolumio Plugin failed to authenticate in a timely fashion Aug 31 18:33:29 volumio volumio[13282]: info: Completed starting MyVolumio Plugin Aug 31 18:33:29 volumio volumio[13282]: info: BOOT COMPLETED Aug 31 18:33:29 volumio volumio[13282]: [Metrics] CommandRouter: 53s 254.20ms Aug 31 18:33:29 volumio volumio[13282]: info: CoreCommandRouter::volumiosetStartupVolume Aug 31 18:33:29 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 31 18:33:29 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 31 18:33:29 volumio volumio[13282]: info: CoreCommandRouter::Close All Modals sent Aug 31 18:33:29 volumio volumio[13282]: info: CoreCommandRouter::Close All Modals sent Aug 31 18:33:30 volumio ntpd[593]: Soliciting pool server 84.16.67.12 Aug 31 18:33:30 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Aug 31 18:33:30 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 31 18:33:31 volumio volumio[13282]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Aug 31 18:33:32 volumio ntpd[593]: Soliciting pool server 112.53.133.30 Aug 31 18:33:45 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d7002.f59a2123 does not match aorg 0xea7d7008.f598d1f7 from server@112.53.133.30 xmt 0xea7d7003.5b721097 Aug 31 18:33:46 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d7004.f59abbb2 does not match aorg 0xea7d7008.f598d1f7 from server@112.53.133.30 xmt 0xea7d7005.3a5c5399 Aug 31 18:33:46 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d7004.f5a653f1 does not match aorg 0xea7d7008.f5a5c304 from server@84.16.67.12 xmt 0xea7d7005.61028d1c Aug 31 18:33:46 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d7006.f59a7204 does not match aorg 0xea7d7008.f598d1f7 from server@112.53.133.30 xmt 0xea7d7007.1581b10b Aug 31 18:33:46 volumio ntpd[593]: receive: Unexpected origin timestamp 0xea7d7006.f5a550e5 does not match aorg 0xea7d7008.f5a5c304 from server@84.16.67.12 xmt 0xea7d7007.3c2be69b Aug 31 18:33:46 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/A-Lin/%E5%A4%A9%E8%8B%A5%E6%9C%89%E6%83%85/b87633f6-30ca-4efc-885d-06f71ed1991e.jpg' Aug 31 18:33:47 volumio volumio[13282]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Aug 31 18:33:47 volumio volumio[13282]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:33:47 volumio volumio[13282]: Error: connect ECONNREFUSED 47.88.58.234:443 Aug 31 18:33:47 volumio volumio[13282]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 31 18:33:47 volumio volumio[13282]: errno: -111, Aug 31 18:33:47 volumio volumio[13282]: code: 'ECONNREFUSED', Aug 31 18:33:47 volumio volumio[13282]: syscall: 'connect', Aug 31 18:33:47 volumio volumio[13282]: address: '47.88.58.234', Aug 31 18:33:47 volumio volumio[13282]: port: 443 Aug 31 18:33:47 volumio volumio[13282]: } Aug 31 18:33:47 volumio volumio[13282]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 31 18:33:48 volumio sudo[13796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-31 18:32 Aug 31 18:33:48 volumio sudo[13796]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"