-- 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"