-- Logs begin at Wed 2024-07-10 03:38:33 EDT, end at Wed 2024-07-10 17:43:15 EDT. -- Jul 10 17:42:04 volumio smbd[10504]: [2024/07/10 17:42:04.516907, 0] ../source3/lib/util.c:493(reinit_after_fork) Jul 10 17:42:04 volumio smbd[10504]: messaging_reinit() failed: NT_STATUS_IO_DEVICE_ERROR Jul 10 17:42:04 volumio smbd[10504]: [2024/07/10 17:42:04.517215, 0] ../source3/printing/print_cups.c:486(cups_pcap_load_async) Jul 10 17:42:04 volumio smbd[10504]: cups_pcap_load_async: reinit_after_fork() failed Jul 10 17:42:04 volumio smbd[10504]: [2024/07/10 17:42:04.517291, 0] ../source3/lib/util.c:816(smb_panic_s3) Jul 10 17:42:04 volumio smbd[10504]: PANIC (pid 10504): cups_pcap_load_async: reinit_after_fork() failed Jul 10 17:42:04 volumio kernel: EXT4-fs warning (device sda3): dx_probe:822: inode #60: lblock 0: comm lpqd: error -5 reading directory block Jul 10 17:42:04 volumio kernel: EXT4-fs warning (device sda3): dx_probe:822: inode #60: lblock 0: comm lpqd: error -5 reading directory block Jul 10 17:42:04 volumio smbd[10504]: [2024/07/10 17:42:04.519841, 0] ../lib/util/fault.c:261(log_stack_trace) Jul 10 17:42:04 volumio smbd[10504]: BACKTRACE: 0 stack frames: Jul 10 17:42:04 volumio smbd[10504]: [2024/07/10 17:42:04.520009, 0] ../source3/lib/dumpcore.c:315(dump_core) Jul 10 17:42:04 volumio smbd[10504]: dumping core in /var/log/samba/cores/smbd Jul 10 17:42:04 volumio smbd[10504]: Jul 10 17:42:04 volumio smbd[1242]: [2024/07/10 17:42:04.530823, 0] ../source3/printing/print_cups.c:524(cups_async_callback) Jul 10 17:42:04 volumio smbd[1242]: failed to recv pcap blob Jul 10 17:42:16 volumio nmbd[10547]: [2024/07/10 17:42:16.529543, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 10 17:42:16 volumio nmbd[10547]: wins_write_database: Can't open /var/lib/samba/wins.dat.10547: Read-only file system Jul 10 17:42:36 volumio nmbd[10548]: [2024/07/10 17:42:36.555889, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 10 17:42:36 volumio nmbd[10548]: wins_write_database: Can't open /var/lib/samba/wins.dat.10548: Read-only file system Jul 10 17:42:36 volumio go-librespot[10494]: fatal error: unexpected signal during runtime execution Jul 10 17:42:36 volumio go-librespot[10494]: [signal SIGBUS: bus error code=0x2 addr=0x93ad64 pc=0x769b8] Jul 10 17:42:36 volumio go-librespot[10494]: runtime stack: Jul 10 17:42:36 volumio go-librespot[10494]: runtime.throw({0x63190e, 0x2a}) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1047 +0x4c fp=0xadafe980 sp=0xadafe96c pc=0x4f284 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.sigpanic() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/signal_unix.go:825 +0x29c fp=0xadafe99c sp=0xadafe980 pc=0x68780 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gentraceback(0xffffffff, 0xffffffff, 0x0, 0x14020f0, 0x0, 0x0, 0x7fffffff, 0xadafecf0, 0x0, 0x0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:135 +0x33c fp=0xadafeba4 sp=0xadafe9a0 pc=0x769b8 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.scanstack(0x14020f0, 0x143625c) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:804 +0x1fc fp=0xadafecfc sp=0xadafeba4 pc=0x34604 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.markroot.func1() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:239 +0xf4 fp=0xadafed28 sp=0xadafecfc pc=0x32ea0 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.markroot(0x143625c, 0x6, 0x1) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:213 +0x234 fp=0xadafed84 sp=0xadafed28 pc=0x32a84 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcDrain(0x143625c, 0x7) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:1069 +0x58c fp=0xadafedcc sp=0xadafed84 pc=0x35490 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcBgMarkWorker.func2() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1367 +0x7c fp=0xadafedf8 sp=0xadafedcc pc=0x30100 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.systemstack() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xadafedfc sp=0xadafedf8 pc=0x82b10 Jul 10 17:42:36 volumio go-librespot[10494]: goroutine 50 [GC worker (active)]: Jul 10 17:42:36 volumio go-librespot[10494]: runtime.systemstack_switch() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x15ea790 sp=0x15ea78c pc=0x82aa4 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcBgMarkWorker() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1335 +0x214 fp=0x15ea7ec sp=0x15ea790 pc=0x2fccc Jul 10 17:42:36 volumio go-librespot[10494]: runtime.goexit() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:831 +0x4 fp=0x15ea7ec sp=0x15ea7ec pc=0x84730 Jul 10 17:42:36 volumio go-librespot[10494]: created by runtime.gcBgMarkStartWorkers Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1199 +0x20 Jul 10 17:42:36 volumio go-librespot[10494]: goroutine 1 [GC assist marking (scan)]: Jul 10 17:42:36 volumio go-librespot[10494]: runtime.systemstack_switch() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:274 +0x4 fp=0x152f128 sp=0x152f124 pc=0x82aa4 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcAssistAlloc(0x14020f0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:474 +0x3e4 fp=0x152f194 sp=0x152f128 pc=0x33864 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.deductAssistCredit(0x8) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/malloc.go:1217 +0x68 fp=0x152f1a4 sp=0x152f194 pc=0x21d44 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.mallocgc(0x8, 0x588c00, 0x1) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/malloc.go:932 +0x12c fp=0x152f1dc sp=0x152f1a4 pc=0x21318 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.convTstring({0x1539920, 0x1c}) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/iface.go:392 +0x44 fp=0x152f1f0 sp=0x152f1dc pc=0x1f2d8 Jul 10 17:42:36 volumio go-librespot[10494]: fatal error: unexpected signal during runtime execution Jul 10 17:42:36 volumio go-librespot[10494]: panic during panic Jul 10 17:42:36 volumio go-librespot[10494]: [signal SIGBUS: bus error code=0x2 addr=0x93ad64 pc=0x769b8] Jul 10 17:42:36 volumio go-librespot[10494]: runtime stack: Jul 10 17:42:36 volumio go-librespot[10494]: runtime.throw({0x63190e, 0x2a}) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1047 +0x4c fp=0xadafe598 sp=0xadafe584 pc=0x4f284 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.sigpanic() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/signal_unix.go:825 +0x29c fp=0xadafe5b4 sp=0xadafe598 pc=0x68780 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gentraceback(0xffffffff, 0xffffffff, 0x0, 0x14020f0, 0x0, 0x0, 0x64, 0x0, 0x0, 0x0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:135 +0x33c fp=0xadafe7bc sp=0xadafe5b8 pc=0x769b8 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x14020f0, 0x0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:776 +0x1a8 fp=0xadafe89c sp=0xadafe7bc pc=0x78c78 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.traceback(...) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:723 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.tracebackothers.func1(0x14020f0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:992 +0x114 fp=0xadafe8b8 sp=0xadafe89c pc=0x79bd4 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.forEachGRace(0xadafe8f0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/proc.go:604 +0x48 fp=0xadafe8d0 sp=0xadafe8b8 pc=0x533a0 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.tracebackothers(0x1402870) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:978 +0xcc fp=0xadafe900 sp=0xadafe8d0 pc=0x79aa8 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.dopanic_m(0x1402870, 0x4f284, 0xadafe96c) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1273 +0x290 fp=0xadafe930 sp=0xadafe900 pc=0x4fb84 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.fatalthrow.func1() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1127 +0x64 fp=0xadafe950 sp=0xadafe930 pc=0x4f5e4 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.fatalthrow(0x2) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1120 +0x64 fp=0xadafe96c sp=0xadafe950 pc=0x4f568 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.throw({0x63190e, 0x2a}) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/panic.go:1047 +0x4c fp=0xadafe980 sp=0xadafe96c pc=0x4f284 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.sigpanic() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/signal_unix.go:825 +0x29c fp=0xadafe99c sp=0xadafe980 pc=0x68780 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gentraceback(0xffffffff, 0xffffffff, 0x0, 0x14020f0, 0x0, 0x0, 0x7fffffff, 0xadafecf0, 0x0, 0x0) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/traceback.go:135 +0x33c fp=0xadafeba4 sp=0xadafe9a0 pc=0x769b8 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.scanstack(0x14020f0, 0x143625c) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:804 +0x1fc fp=0xadafecfc sp=0xadafeba4 pc=0x34604 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.markroot.func1() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:239 +0xf4 fp=0xadafed28 sp=0xadafecfc pc=0x32ea0 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.markroot(0x143625c, 0x6, 0x1) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:213 +0x234 fp=0xadafed84 sp=0xadafed28 pc=0x32a84 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcDrain(0x143625c, 0x7) Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgcmark.go:1069 +0x58c fp=0xadafedcc sp=0xadafed84 pc=0x35490 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.gcBgMarkWorker.func2() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/mgc.go:1367 +0x7c fp=0xadafedf8 sp=0xadafedcc pc=0x30100 Jul 10 17:42:36 volumio go-librespot[10494]: runtime.systemstack() Jul 10 17:42:36 volumio go-librespot[10494]: /opt/hostedtoolcache/go/1.20.8/x64/src/runtime/asm_arm.s:317 +0x60 fp=0xadafedfc sp=0xadafedf8 pc=0x82b10 Jul 10 17:42:36 volumio go-librespot[10494]: Aborted Jul 10 17:42:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 10 17:42:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 10 17:42:36 volumio volumio[975]: info: Connection to go-librespot Websocket closed Jul 10 17:42:39 volumio volumio[975]: info: Initializing connection to go-librespot Websocket Jul 10 17:42:39 volumio volumio[975]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 10 17:42:39 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 10 17:42:39 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: dev-sdb1.device: Cannot add dependency job, ignoring: Unit dev-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: dev-sdb1.device: Cannot add dependency job, ignoring: Unit dev-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: dev-sdb1.device: Cannot add dependency job, ignoring: Unit dev-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: dev-sdb1.device: Cannot add dependency job, ignoring: Unit dev-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device: Cannot add dependency job, ignoring: Unit sys-devices-platform-scb-fd500000.pcie-pci0000:00-0000:00:00.0-0000:01:00.0-usb2-2\x2d2-2\x2d2:1.0-host1-target1:0:0-1:0:0:0-block-sdb-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: dev-sdb1.device: Cannot add dependency job, ignoring: Unit dev-sdb1.device not found. Jul 10 17:42:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 340. Jul 10 17:42:39 volumio systemd[1]: Stopped go-librespot Daemon. Jul 10 17:42:39 volumio systemd[1]: Started go-librespot Daemon. Jul 10 17:42:39 volumio go-librespot[10549]: Librespot-go daemon starting... Jul 10 17:42:39 volumio go-librespot[10549]: time="2024-07-10T17:42:39-04:00" level=info msg="generated new device id: cc46dbec192c72ce2b56d962edded77270ceca1b" Jul 10 17:42:39 volumio go-librespot[10549]: time="2024-07-10T17:42:39-04:00" level=debug msg="stored credentials found for sk8twoollie" Jul 10 17:42:40 volumio go-librespot[10549]: time="2024-07-10T17:42:40-04:00" level=debug msg="obtained new client token: AAAwkfLd9oTin2NvcimOJAA9/khsDsB38WFGHvzWMe18iCr6nV4H1fGGxQO6MeY3vV+Y0qh0WJQPAVHxmuYe/xNpMn4CjQR5LTHk5bjBEuVd9YKt6aBjMis2N+Y3mr/3ecFYtcwT3TJUybUpE3xp5+JthmwTU37vnpu+OChaWUxC8Eg50toCsbVFfp9CquHf3S6U8kO7J+lB9utemSuwq/tAHMoMZTj5PIuOeHfGd7VFKMwaEJBAIS5fH8y6Sg==" Jul 10 17:42:40 volumio go-librespot[10549]: time="2024-07-10T17:42:40-04:00" level=debug msg="fetched new accesspoints: [ap-gue1.spotify.com:4070 ap-gue1.spotify.com:443 ap-gue1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gae2.spotify.com:443 ap-gew1.spotify.com:80]" Jul 10 17:42:40 volumio go-librespot[10549]: time="2024-07-10T17:42:40-04:00" level=debug msg="completed keyexchange" Jul 10 17:42:40 volumio go-librespot[10549]: time="2024-07-10T17:42:40-04:00" level=debug msg="completed challenge" Jul 10 17:42:40 volumio go-librespot[10549]: time="2024-07-10T17:42:40-04:00" level=debug msg="authenticated as sk8twoollie" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="authenticated as sk8twoollie" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="fetched new spclients: [gue1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gae2-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="fetched new dealers: [gue1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="dealer connection opened" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 513" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="received connection id: OTFlYjYzMzItN2Q4NC00NzAxLWI2ODEtZTU4YmMwODVhNWUyK2RlYWxlcit0Y3A6Ly9ndWUxLWRlYWxlci1hLW02bHMuZ3VlMS5zcG90aWZ5Lm5ldDo1NzAwK0MyMTQ4QzFBMUVFQThFOEQ3RDlDQTQ2NzQwMUZGNEIzNTU4QUVBMTc2RDAxM0E2Qjc0Qzg2REU0NjMyQ0NBNzc=" Jul 10 17:42:41 volumio go-librespot[10549]: time="2024-07-10T17:42:41-04:00" level=debug msg="put connect state because NEW_DEVICE" Jul 10 17:42:42 volumio volumio[975]: info: Initializing connection to go-librespot Websocket Jul 10 17:42:42 volumio go-librespot[10549]: time="2024-07-10T17:42:42-04:00" level=debug msg="new websocket client" Jul 10 17:42:42 volumio volumio[975]: info: Connection to go-librespot Websocket established Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="handling transfer player command from cc2e104ea96f6b736a9011612b3c3bfea4ddb234" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="shuffled context with seed 14036684728463149711 (len: 1, keep: -1)" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="loading track spotify:track:6bUNEbXT7HovLW6BgPCBsb (paused: true, position: 293ms)" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="emitting websocket event: will_play" Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6bUNEbXT7HovLW6BgPCBsb","play_origin":"com.spotify.service.search2"}} Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:6bUNEbXT7HovLW6BgPCBsb" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="requested aes key for file 0b60b381505c4277c90ed740e6a025fe115f715c, gid: 6bUNEbXT7HovLW6BgPCBsb" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1943" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1546" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="fetched first chunk of 34, total size is 17401884 bytes" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="seek to 293ms (diff: 293ms, samples: 12921, bytes: 0)" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=info msg="loaded track \"Roses\" (uri: spotify:track:6bUNEbXT7HovLW6BgPCBsb, paused: true, position: 293ms, duration: 369573ms)" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="fetched chunk 1/33, size: 524288" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="emitting websocket event: metadata" Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6bUNEbXT7HovLW6BgPCBsb","name":"Roses","artist_names":["Outkast"],"album_name":"Speakerboxxx/The Love Below","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026a6387ab37f64034cdc7b367","position":293,"duration":369573,"release_date":"year:2003","track_number":10,"disc_number":2}} Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="emitting websocket event: active" Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: received: {"type":"active","data":null} Jul 10 17:42:43 volumio volumio[975]: info: Aligning Spotify Volume to Volumio Volume Jul 10 17:42:43 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:42:43 volumio volumio[975]: info: Setting Spotify Volume from Volumio: 19 Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="sending successful reply for delaer request" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="fetched chunk 2/33, size: 524288" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=trace msg="emitting websocket event: paused" Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:6bUNEbXT7HovLW6BgPCBsb","play_origin":"com.spotify.service.search2"}} Jul 10 17:42:43 volumio volumio[975]: info: Spotify is playing in volatile mode Jul 10 17:42:43 volumio volumio[975]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: UNSET VOLATILE Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: {"status":"stop","position":0,"title":"Catch The Beat","artist":"Daily Bread","album":"Flash Flood II Mixtape","albumart":"https://i1.sndcdn.com/artworks-gS17VRAO2MDOu4Oq-EcMmPQ-t500x500.jpg","uri":"soundcloud/track@trackId=1850449071@origin:o=%7B%22type%22%3A%22playlist%22%2C%22playlistId%22%3A1838805846%7D","trackType":"mp3","seek":6520,"duration":210,"samplerate":"128 kbps","channels":2,"bitrate":null,"random":true,"repeat":null,"repeatSingle":false,"consume":true,"volume":19,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":false,"service":"soundcloud"} Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: PUSH STATE SPOTIFY Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: {"status":"pause","service":"spop","title":"Roses","artist":"Outkast","album":"Speakerboxxx/The Love Below","albumart":"https://i.scdn.co/image/ab67616d00001e026a6387ab37f64034cdc7b367","uri":"spotify:track:6bUNEbXT7HovLW6BgPCBsb","trackType":"spotify","seek":293,"duration":369,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 10 17:42:43 volumio volumio[975]: info: CoreCommandRouter::servicePushState Jul 10 17:42:43 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:43 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:43 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:43 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:43 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:43 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jul 10 17:42:43 volumio go-librespot[10549]: time="2024-07-10T17:42:43-04:00" level=debug msg="fetched chunk 3/33, size: 524288" Jul 10 17:42:45 volumio volumio[975]: SPOTIFY: SETTING SPOTIFY VOLUME 19 Jul 10 17:42:45 volumio volumio[975]: info: Sending Spotify command with payload to local API: /player/volume Jul 10 17:42:45 volumio go-librespot[10549]: time="2024-07-10T17:42:45-04:00" level=debug msg="update volume to 12451/65535" Jul 10 17:42:45 volumio go-librespot[10549]: time="2024-07-10T17:42:45-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 17:42:45 volumio go-librespot[10549]: time="2024-07-10T17:42:45-04:00" level=trace msg="emitting websocket event: volume" Jul 10 17:42:45 volumio volumio[975]: SPOTIFY: received: {"type":"volume","data":{"value":19,"max":100}} Jul 10 17:42:45 volumio volumio[975]: SPOTIFY: RECEIVED SPOTIFY VOLUME 19 Jul 10 17:42:45 volumio volumio[975]: info: Getting Spotify volume Jul 10 17:42:45 volumio volumio[975]: info: Spotify volume: 19 Jul 10 17:42:45 volumio volumio[975]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 10 17:42:45 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:42:45 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jul 10 17:42:47 volumio go-librespot[10549]: time="2024-07-10T17:42:47-04:00" level=debug msg="handling resume player command from cc2e104ea96f6b736a9011612b3c3bfea4ddb234" Jul 10 17:42:47 volumio go-librespot[10549]: time="2024-07-10T17:42:47-04:00" level=trace msg="seek to 293ms (diff: 293ms, samples: 12921, bytes: 0)" Jul 10 17:42:47 volumio go-librespot[10549]: time="2024-07-10T17:42:47-04:00" level=debug msg="resume track at 0ms" Jul 10 17:42:48 volumio go-librespot[10549]: time="2024-07-10T17:42:48-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 17:42:48 volumio go-librespot[10549]: time="2024-07-10T17:42:48-04:00" level=debug msg="sending successful reply for delaer request" Jul 10 17:42:48 volumio go-librespot[10549]: time="2024-07-10T17:42:48-04:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jul 10 17:42:48 volumio go-librespot[10549]: time="2024-07-10T17:42:48-04:00" level=trace msg="emitting websocket event: playing" Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6bUNEbXT7HovLW6BgPCBsb","play_origin":"com.spotify.service.search2"}} Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: PUSH STATE SPOTIFY Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Jul 10 17:42:48 volumio volumio[975]: info: CoreCommandRouter::servicePushState Jul 10 17:42:48 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:48 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:48 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:48 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:48 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: PUSH STATE SPOTIFY Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jul 10 17:42:48 volumio volumio[975]: info: CoreCommandRouter::servicePushState Jul 10 17:42:48 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:48 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:48 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:48 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:48 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 19 Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=debug msg="update volume to 16383/65535" Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=trace msg="emitting websocket event: volume" Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: received: {"type":"volume","data":{"value":25,"max":100}} Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: RECEIVED SPOTIFY VOLUME 25 Jul 10 17:42:52 volumio volumio[975]: info: Setting Volumio Volume from Spotify: 25 Jul 10 17:42:52 volumio volumio[975]: info: VolumeController::SetAlsaVolume25 Jul 10 17:42:52 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:52 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 25 Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=debug msg="update volume to 20315/65535" Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=trace msg="emitting websocket event: volume" Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: received: {"type":"volume","data":{"value":31,"max":100}} Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: RECEIVED SPOTIFY VOLUME 31 Jul 10 17:42:52 volumio volumio[975]: info: Setting Volumio Volume from Spotify: 31 Jul 10 17:42:52 volumio volumio[975]: info: VolumeController::SetAlsaVolume31 Jul 10 17:42:52 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:52 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:52 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:52 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:52 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 31 Jul 10 17:42:52 volumio go-librespot[10549]: time="2024-07-10T17:42:52-04:00" level=debug msg="update volume to 24247/65535" Jul 10 17:42:53 volumio go-librespot[10549]: time="2024-07-10T17:42:53-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 17:42:53 volumio go-librespot[10549]: time="2024-07-10T17:42:53-04:00" level=trace msg="emitting websocket event: volume" Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: received: {"type":"volume","data":{"value":37,"max":100}} Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: RECEIVED SPOTIFY VOLUME 37 Jul 10 17:42:53 volumio volumio[975]: info: Setting Volumio Volume from Spotify: 37 Jul 10 17:42:53 volumio volumio[975]: info: VolumeController::SetAlsaVolume37 Jul 10 17:42:53 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:53 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:53 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:53 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 37 Jul 10 17:42:53 volumio go-librespot[10549]: time="2024-07-10T17:42:53-04:00" level=debug msg="update volume to 28180/65535" Jul 10 17:42:53 volumio go-librespot[10549]: time="2024-07-10T17:42:53-04:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 10 17:42:53 volumio go-librespot[10549]: time="2024-07-10T17:42:53-04:00" level=trace msg="emitting websocket event: volume" Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: received: {"type":"volume","data":{"value":43,"max":100}} Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: RECEIVED SPOTIFY VOLUME 43 Jul 10 17:42:53 volumio volumio[975]: info: Setting Volumio Volume from Spotify: 43 Jul 10 17:42:53 volumio volumio[975]: info: VolumeController::SetAlsaVolume43 Jul 10 17:42:53 volumio volumio[975]: info: CoreStateMachine::pushState Jul 10 17:42:53 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:42:53 volumio volumio[975]: info: CoreCommandRouter::volumioPushState Jul 10 17:42:53 volumio volumio[975]: info: MRS: Pushing multiroomSync output update for this device Jul 10 17:42:53 volumio volumio[975]: info: MRS: Pushing multiroomSync output Jul 10 17:42:53 volumio volumio[975]: SPOTIFY: RECEIVED VOLUMIO VOLUME 43 Jul 10 17:42:56 volumio nmbd[10567]: [2024/07/10 17:42:56.582306, 0] ../source3/nmbd/nmbd_winsserver.c:2508(wins_write_database) Jul 10 17:42:56 volumio nmbd[10567]: wins_write_database: Can't open /var/lib/samba/wins.dat.10567: Read-only file system Jul 10 17:42:59 volumio go-librespot[10549]: time="2024-07-10T17:42:59-04:00" level=debug msg="fetched chunk 4/33, size: 524288" Jul 10 17:43:11 volumio go-librespot[10549]: time="2024-07-10T17:43:11-04:00" level=debug msg="fetched chunk 5/33, size: 524288" Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:43:12 volumio volumio[975]: info: Discovery: Getting this device information Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:43:12 volumio volumio[975]: verbose: New Socket.io Connection to 10.0.0.26 from 10.0.0.36 UA: unknown Engine version: 3 Transport: websocket Total Clients: 6 Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 10 17:43:12 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 10 17:43:14 volumio sudo[10610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 17:43:14 volumio sudo[10610]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:43:14 volumio sudo[10610]: pam_unix(sudo:session): session closed for user root Jul 10 17:43:14 volumio sudo[10613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 17:43:14 volumio sudo[10613]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 10 17:43:14 volumio sudo[10613]: pam_unix(sudo:session): session closed for user root Jul 10 17:43:14 volumio volumio[975]: verbose: New Socket.io Connection to 10.0.0.26 from 10.0.0.36 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Engine version: 3 Transport: polling Total Clients: 7 Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::volumioGetVisibleSources Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 17:43:15 volumio volumio[975]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jul 10 17:43:15 volumio volumio[975]: info: Received Get System Info Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:43:15 volumio volumio[975]: info: Discovery: Getting this device information Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:43:15 volumio volumio[975]: info: CoreCommandRouter::volumioGetState Jul 10 17:43:15 volumio volumio[975]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:43:15 volumio kernel: EXT4-fs warning (device sda3): htree_dirblock_to_tree:1082: inode #116: lblock 0: comm node: error -5 reading directory block Jul 10 17:43:15 volumio volumio[975]: Error: EIO: i/o error, scandir '/data/plugins/' Jul 10 17:43:15 volumio volumio[975]: at Object.readdirSync (fs.js:1021:3) Jul 10 17:43:15 volumio volumio[975]: at PluginManager.getInstalledPlugins (/volumio/app/pluginmanager.js:1627:30) Jul 10 17:43:15 volumio volumio[975]: at CoreCommandRouter.getInstalledPlugins (/volumio/app/index.js:1521:29) Jul 10 17:43:15 volumio volumio[975]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1321:45) Jul 10 17:43:15 volumio volumio[975]: at Socket.emit (events.js:315:20) Jul 10 17:43:15 volumio volumio[975]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 10 17:43:15 volumio volumio[975]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 10 17:43:15 volumio volumio[975]: errno: -5, Jul 10 17:43:15 volumio volumio[975]: syscall: 'scandir', Jul 10 17:43:15 volumio volumio[975]: code: 'EIO', Jul 10 17:43:15 volumio volumio[975]: path: '/data/plugins/' Jul 10 17:43:15 volumio volumio[975]: } Jul 10 17:43:15 volumio volumio[975]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:43:15 volumio sudo[10624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-10 17:42 Jul 10 17:43:15 volumio sudo[10624]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"