-- Logs begin at Sat 2025-06-14 19:13:16 EEST, end at Sat 2025-06-14 19:26:09 EEST. -- Jun 14 19:25:03 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 19:25:03 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 19:25:03 volumio2 volumio[867]: info: Discovery: Getting this device information Jun 14 19:25:03 volumio2 volumio[867]: info: CoreCommandRouter::volumioGetState Jun 14 19:25:03 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 19:25:08 volumio2 go-librespot[1258]: time="2025-06-14T19:25:08+03:00" level=trace msg="sent dealer ping" Jun 14 19:25:08 volumio2 go-librespot[1258]: time="2025-06-14T19:25:08+03:00" level=trace msg="received dealer pong" Jun 14 19:25:33 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 14 19:25:33 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 14 19:25:33 volumio2 volumio[867]: info: Discovery: Getting this device information Jun 14 19:25:33 volumio2 volumio[867]: info: CoreCommandRouter::volumioGetState Jun 14 19:25:33 volumio2 volumio[867]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 14 19:25:38 volumio2 go-librespot[1258]: time="2025-06-14T19:25:38+03:00" level=trace msg="sent dealer ping" Jun 14 19:25:38 volumio2 go-librespot[1258]: time="2025-06-14T19:25:38+03:00" level=trace msg="received dealer pong" Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.695381, 0] ../lib/util/fault.c:79(fault_report) Jun 14 19:25:46 volumio2 smbd[3619]: =============================================================== Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.696796, 0] ../lib/util/fault.c:80(fault_report) Jun 14 19:25:46 volumio2 smbd[3619]: INTERNAL ERROR: Signal 11 in pid 3619 (4.9.5-Debian) Jun 14 19:25:46 volumio2 smbd[3619]: Please read the Trouble-Shooting section of the Samba HOWTO Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.696932, 0] ../lib/util/fault.c:82(fault_report) Jun 14 19:25:46 volumio2 smbd[3619]: =============================================================== Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.697015, 0] ../source3/lib/util.c:816(smb_panic_s3) Jun 14 19:25:46 volumio2 smbd[3619]: PANIC (pid 3619): internal error Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.936952, 0] ../lib/util/fault.c:261(log_stack_trace) Jun 14 19:25:46 volumio2 smbd[3619]: BACKTRACE: 0 stack frames: Jun 14 19:25:46 volumio2 smbd[3619]: [2025/06/14 19:25:46.937329, 0] ../source3/lib/dumpcore.c:315(dump_core) Jun 14 19:25:46 volumio2 smbd[3619]: dumping core in /var/log/samba/cores/smbd Jun 14 19:25:46 volumio2 smbd[3619]: Jun 14 19:25:46 volumio2 smbd[900]: [2025/06/14 19:25:46.967646, 0] ../source3/printing/print_cups.c:524(cups_async_callback) Jun 14 19:25:46 volumio2 smbd[900]: failed to recv pcap blob Jun 14 19:25:47 volumio2 volumio-remote-updater[531]: [2025-06-14 19:25:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 14 19:25:47 volumio2 systemd[1]: volumio.service: Main process exited, code=killed, status=11/SEGV Jun 14 19:25:47 volumio2 volumio-remote-updater[531]: [2025-06-14 19:25:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 14 19:25:47 volumio2 systemd[1]: volumio.service: Failed with result 'signal'. Jun 14 19:25:47 volumio2 go-librespot[1258]: unexpected fault address 0x58162100 Jun 14 19:25:47 volumio2 go-librespot[1258]: fatal error: fault Jun 14 19:25:47 volumio2 go-librespot[1258]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x58162100 pc=0x94538] Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 25 gp=0x12fe368 m=5 mp=0x1080008 [running]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.throw({0x70a5fe, 0x5}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/panic.go:1023 +0x4c fp=0x1055860 sp=0x105584c pc=0x59624 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.sigpanic() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/signal_unix.go:895 +0x2c0 fp=0x1055890 sp=0x1055860 pc=0x74a10 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.memmove(0x581620e4, 0x10559a4, 0x1d) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/memmove_arm.s:85 +0x34 fp=0x105589c sp=0x1055894 pc=0x94538 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.(*buffer).writeString(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/print.go:108 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.(*pp).doPrintf(0x110e148, {0x10559a4, 0x1f}, {0x1106240, 0x1, 0x1}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/print.go:1032 +0x10c fp=0x1055918 sp=0x105589c pc=0x127904 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.Errorf({0x10559a4, 0x1f}, {0x1106240, 0x1, 0x1}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/errors.go:25 +0x54 fp=0x105597c sp=0x1055918 pc=0x11e028 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket/internal/errd.Wrap(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/internal/errd/wrap.go:12 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.readFrameHeader.deferwrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:52 +0x10c fp=0x10559d8 sp=0x105597c pc=0x470e9c Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.readFrameHeader(0x10c01b0, {0x11f23f8, 0x8, 0x8}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:56 +0x45c fp=0x1055a18 sp=0x10559d8 pc=0x470d38 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1055ad8 sp=0x1055a18 pc=0x4735b4 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).readLoop(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1055b9c sp=0x1055ad8 pc=0x47309c Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).reader(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1055c28 sp=0x1055b9c pc=0x4742b0 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).Read(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 systemd[1]: Started dynamicswap service. Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1055c50 sp=0x1055c28 pc=0x472980 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x1055ccc sp=0x1055c50 pc=0x5e9490 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.HandlerFunc.ServeHTTP(0x1031658, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1055cdc sp=0x1055ccc pc=0x33d320 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*ServeMux).ServeHTTP(0x11a2900, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1055d18 sp=0x1055cdc pc=0x33f180 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1055d40 sp=0x1055d18 pc=0x5e8e90 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.HandlerFunc.ServeHTTP(0x100f2a0, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1055d50 sp=0x1055d40 pc=0x33d320 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.serverHandler.ServeHTTP({0x107fc28}, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1055d6c sp=0x1055d50 pc=0x340910 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*conn).serve(0x10e0060, {0x7d7654, 0x10b2138}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1055fdc sp=0x1055d6c pc=0x33bb60 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*Server).Serve.gowrap3() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1055fec sp=0x1055fdc pc=0x3411b4 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1055fec sp=0x1055fec pc=0x93c4c Jun 14 19:25:47 volumio2 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 14 19:25:47 volumio2 go-librespot[1258]: created by net/http.(*Server).Serve in goroutine 35 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 1 gp=0x1002128 m=nil [select, 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14c7cfc sp=0x14c7ce8 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x14c7dfc, 0x11f9dc8, 0x0, 0x0, 0x2, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x14c7d9c sp=0x14c7cfc pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x10e0960, 0x14c7e88) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x14c7e40 sp=0x14c7d9c pc=0x55dd34 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).withAppPlayer(0x107acc0, {0x7d7634, 0xbf6000}, 0x10a8100) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:316 +0x514 fp=0x14c7ec8 sp=0x14c7e40 pc=0x5f2dcc Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).withCredentials(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:190 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).SpotifyToken(0x107acc0, {0x7d7634, 0xbf6000}, {0x10106e0, 0xf}, {0x119e120, 0x11a}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:182 +0xd8 fp=0x14c7ef8 sp=0x14c7ec8 pc=0x5f22c0 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.main() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:618 +0x61c fp=0x14c7fa8 sp=0x14c7ef8 pc=0x5f5a58 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.main() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x14c7fec sp=0x14c7fa8 pc=0x5ca38 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14c7fec sp=0x14c7fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 18 gp=0x1100008 m=nil [force gc (idle), 3 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10407d4 sp=0x10407c0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.forcegchelper() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x10407ec sp=0x10407d4 pc=0x5ce04 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10407ec sp=0x10407ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.init.5 in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 19 gp=0x1100128 m=nil [GC sweep wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1040fc4 sp=0x1040fb0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.bgsweep(0x1104000) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1040fe4 sp=0x1040fc4 pc=0x45750 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcenable.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1040fec sp=0x1040fe4 pc=0x35870 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1040fec sp=0x1040fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcenable in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 20 gp=0x1100248 m=nil [GC scavenge wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10417b4 sp=0x10417a0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.(*scavengerState).park(0xbcb508) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x10417c8 sp=0x10417b4 pc=0x42a70 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.bgscavenge(0x1104000) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x10417e4 sp=0x10417c8 pc=0x431a8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcenable.gowrap2() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x10417ec sp=0x10417e4 pc=0x3581c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 systemd[1]: Stopped Volumio Backend Module. Jun 14 19:25:47 volumio2 systemd[1]: Started Volumio Backend Module. Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10417ec sp=0x10417ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcenable in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 21 gp=0x1100368 m=nil [finalizer wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1041f8c sp=0x1041f78 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.runfinq() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1041fec sp=0x1041f8c pc=0x3465c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1041fec sp=0x1041fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.createfing in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 35 gp=0x11007e8 m=nil [IO wait, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7ec8, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1059d28 sp=0x1059d14 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7eb8, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1059d40 sp=0x1059d28 pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7eb8, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1059d54 sp=0x1059d40 pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x109c158, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1059d68 sp=0x1059d54 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).Accept(0x109c140) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1059db0 sp=0x1059d68 pc=0x10eb44 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*netFD).accept(0x109c140) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1059e18 sp=0x1059db0 pc=0x17e32c Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*TCPListener).accept(0x10b20f0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1059e30 sp=0x1059e18 pc=0x19642c Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*TCPListener).Accept(0x10b20f0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1059e4c sp=0x1059e30 pc=0x195498 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*onceCloseListener).Accept(0x1075760) Jun 14 19:25:47 volumio2 go-librespot[1258]: :1 +0x34 fp=0x1059e64 sp=0x1059e4c pc=0x362680 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*Server).Serve(0x107fc28, {0x7d6e18, 0x10b20f0}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1059efc sp=0x1059e64 pc=0x340d3c Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.Serve(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2794 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve(0x11222a0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x1059fe4 sp=0x1059efc pc=0x5e8bc0 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.NewApiServer.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x1059fec sp=0x1059fe4 pc=0x5e7a90 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1059fec sp=0x1059fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by main.NewApiServer in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:289 +0x310 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 38 gp=0x10846c8 m=nil [IO wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7c48, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14c9e84 sp=0x14c9e70 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7c38, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x14c9e9c sp=0x14c9e84 pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7c38, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x14c9eb0 sp=0x14c9e9c pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x10286f8, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x14c9ec4 sp=0x14c9eb0 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).RawRead(0x10286e0, 0x110c780) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x14c9ef0 sp=0x14c9ec4 pc=0x10f748 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*rawConn).Read(0x1506570, 0x110c780) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x14c9f10 sp=0x14c9ef0 pc=0x190854 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1102870, 0x110c750, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x14c9f40 sp=0x14c9f10 pc=0x4fe72c Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x10c0c38, {0x1300000, 0x10000, 0x10000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x14c9f9c sp=0x14c9f40 pc=0x502714 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).recv4(0x110ac80, 0x10c0c30) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x14c9fe0 sp=0x14c9f9c pc=0x558fa4 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x14c9fec sp=0x14c9fe0 pc=0x558c5c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14c9fec sp=0x14c9fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 37 gp=0x1003d48 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x11178d8, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1044f90 sp=0x1044f7c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1044fec sp=0x1044f90 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1044fec sp=0x1044fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 5 gp=0x12fe008 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x102ae28, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045790 sp=0x104577c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10457ec sp=0x1045790 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10457ec sp=0x10457ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 6 gp=0x12fe128 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x102ae40, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045f90 sp=0x1045f7c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1045fec sp=0x1045f90 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1045fec sp=0x1045fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 7 gp=0x12fe248 m=nil [GC worker (idle)]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x11178f0, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1046790 sp=0x104677c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10467ec sp=0x1046790 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10467ec sp=0x10467ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 27 gp=0x12fe5a8 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x12186a8 sp=0x1218694 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x12187a4, 0x121876c, 0x0, 0x0, 0x5, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1218748 sp=0x12186a8 pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x11f23c8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x12187e4 sp=0x1218748 pc=0x46d7fc Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.newConn.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x12187ec sp=0x12187e4 pc=0x46d3f0 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x12187ec sp=0x12187ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by nhooyr.io/websocket.newConn in goroutine 25 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 39 gp=0x1100488 m=nil [IO wait, 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7ba8, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x121be5c sp=0x121be48 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7b98, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x121be74 sp=0x121be5c pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7b98, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x121be88 sp=0x121be74 pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x1028748, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x121be9c sp=0x121be88 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).RawRead(0x1028730, 0x14a1e60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x121bec8 sp=0x121be9c pc=0x10f748 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*rawConn).Read(0x1506590, 0x14a1e60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x121bee8 sp=0x121bec8 pc=0x190854 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x11028b0, 0x14a1e30, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x121bf18 sp=0x121bee8 pc=0x4fe72c Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x10c0c68, {0x14ca000, 0x10000, 0x10000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x121bf9c sp=0x121bf18 pc=0x50609c Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).recv6(0x110ac80, 0x10c0c60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x121bfe0 sp=0x121bf9c pc=0x559198 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x121bfec sp=0x121bfe0 pc=0x558c00 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x121bfec sp=0x121bfec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 71 gp=0x10030e8 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1047674 sp=0x1047660 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x1047774, 0x104773c, 0x0, 0x0, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1047714 sp=0x1047674 pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x107aa40) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:181 +0x1fc fp=0x10477e4 sp=0x1047714 pc=0x4cbbd8 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:163 +0x28 fp=0x10477ec sp=0x10477e4 pc=0x4cb7b8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10477ec sp=0x10477ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:163 +0x1c8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 72 gp=0x1003208 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x294b7ded, 0x4031436b, 0xab, 0x3f, 0x4031436c) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1267854 sp=0x1267840 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: panic during panic Jun 14 19:25:47 volumio2 go-librespot[1258]: SIGSEGV: segmentation violation Jun 14 19:25:47 volumio2 go-librespot[1258]: PC=0x84ef8 m=5 sigcode=1 addr=0xa8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 0 gp=0x1084128 m=5 mp=0x1080008 [idle]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.(*unwinder).next(0x74cfdc54) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:457 +0x1c4 fp=0x74cfdaa0 sp=0x74cfda54 pc=0x84ef8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.traceback2(0x74cfdc54, 0x0, 0x0, 0x31) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:981 +0x88 fp=0x74cfdbd8 sp=0x74cfdaa0 pc=0x86764 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.traceback1.func1(0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:917 +0x6c fp=0x74cfdc38 sp=0x74cfdbd8 pc=0x865b8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.traceback1(0xffffffff, 0xffffffff, 0x0, 0x1003208, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:940 +0x1a4 fp=0x74cfdd40 sp=0x74cfdc38 pc=0x864a8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.traceback(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:817 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.tracebackothers.func1(0x1003208) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:1259 +0x118 fp=0x74cfdd5c sp=0x74cfdd40 pc=0x87eb0 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.forEachGRace(0x74cfdd94) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:677 +0x4c fp=0x74cfdd74 sp=0x74cfdd5c pc=0x5db14 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.tracebackothers(0x12fe368) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/traceback.go:1245 +0xcc fp=0x74cfdda4 sp=0x74cfdd74 pc=0x87d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.dopanic_m(0x12fe368, 0x59624, 0x105584c) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/panic.go:1345 +0x2a8 fp=0x74cfddd8 sp=0x74cfdda4 pc=0x5a0f4 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.fatalthrow.func1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/panic.go:1199 +0x64 fp=0x74cfddf8 sp=0x74cfddd8 pc=0x59b24 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.systemstack(0x1000152) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:317 +0x60 fp=0x74cfddfc sp=0x74cfddf8 pc=0x91ff8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 25 gp=0x12fe368 m=5 mp=0x1080008 [running]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.systemstack_switch() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:274 +0x4 fp=0x1055830 sp=0x105582c pc=0x91f8c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.fatalthrow(0x2) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/panic.go:1192 +0x64 fp=0x105584c sp=0x1055830 pc=0x59aa8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.throw({0x70a5fe, 0x5}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/panic.go:1023 +0x4c fp=0x1055860 sp=0x105584c pc=0x59624 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.sigpanic() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/signal_unix.go:895 +0x2c0 fp=0x1055890 sp=0x1055860 pc=0x74a10 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.memmove(0x581620e4, 0x10559a4, 0x1d) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/memmove_arm.s:85 +0x34 fp=0x105589c sp=0x1055894 pc=0x94538 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.(*buffer).writeString(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/print.go:108 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.(*pp).doPrintf(0x110e148, {0x10559a4, 0x1f}, {0x1106240, 0x1, 0x1}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/print.go:1032 +0x10c fp=0x1055918 sp=0x105589c pc=0x127904 Jun 14 19:25:47 volumio2 go-librespot[1258]: fmt.Errorf({0x10559a4, 0x1f}, {0x1106240, 0x1, 0x1}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/fmt/errors.go:25 +0x54 fp=0x105597c sp=0x1055918 pc=0x11e028 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket/internal/errd.Wrap(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/internal/errd/wrap.go:12 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.readFrameHeader.deferwrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:52 +0x10c fp=0x10559d8 sp=0x105597c pc=0x470e9c Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.readFrameHeader(0x10c01b0, {0x11f23f8, 0x8, 0x8}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:56 +0x45c fp=0x1055a18 sp=0x10559d8 pc=0x470d38 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1055ad8 sp=0x1055a18 pc=0x4735b4 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).readLoop(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1055b9c sp=0x1055ad8 pc=0x47309c Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).reader(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1055c28 sp=0x1055b9c pc=0x4742b0 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).Read(0x11f23c8, {0x7d7614, 0xbf6000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1055c50 sp=0x1055c28 pc=0x472980 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x1055ccc sp=0x1055c50 pc=0x5e9490 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.HandlerFunc.ServeHTTP(0x1031658, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1055cdc sp=0x1055ccc pc=0x33d320 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*ServeMux).ServeHTTP(0x11a2900, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1055d18 sp=0x1055cdc pc=0x33f180 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1055d40 sp=0x1055d18 pc=0x5e8e90 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.HandlerFunc.ServeHTTP(0x100f2a0, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1055d50 sp=0x1055d40 pc=0x33d320 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.serverHandler.ServeHTTP({0x107fc28}, {0x7d6f4c, 0x10b81e8}, 0x10b80a8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1055d6c sp=0x1055d50 pc=0x340910 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*conn).serve(0x10e0060, {0x7d7654, 0x10b2138}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1055fdc sp=0x1055d6c pc=0x33bb60 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*Server).Serve.gowrap3() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1055fec sp=0x1055fdc pc=0x3411b4 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1055fec sp=0x1055fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by net/http.(*Server).Serve in goroutine 35 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 1 gp=0x1002128 m=nil [select, 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14c7cfc sp=0x14c7ce8 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x14c7dfc, 0x11f9dc8, 0x0, 0x0, 0x2, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x14c7d9c sp=0x14c7cfc pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x10e0960, 0x14c7e88) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x14c7e40 sp=0x14c7d9c pc=0x55dd34 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).withAppPlayer(0x107acc0, {0x7d7634, 0xbf6000}, 0x10a8100) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:316 +0x514 fp=0x14c7ec8 sp=0x14c7e40 pc=0x5f2dcc Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).withCredentials(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:190 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*App).SpotifyToken(0x107acc0, {0x7d7634, 0xbf6000}, {0x10106e0, 0xf}, {0x119e120, 0x11a}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:182 +0xd8 fp=0x14c7ef8 sp=0x14c7ec8 pc=0x5f22c0 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.main() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/main.go:618 +0x61c fp=0x14c7fa8 sp=0x14c7ef8 pc=0x5f5a58 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.main() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x14c7fec sp=0x14c7fa8 pc=0x5ca38 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14c7fec sp=0x14c7fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 18 gp=0x1100008 m=nil [force gc (idle), 3 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10407d4 sp=0x10407c0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.forcegchelper() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x10407ec sp=0x10407d4 pc=0x5ce04 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10407ec sp=0x10407ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.init.5 in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 19 gp=0x1100128 m=nil [GC sweep wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1040fc4 sp=0x1040fb0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.bgsweep(0x1104000) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1040fe4 sp=0x1040fc4 pc=0x45750 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcenable.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1040fec sp=0x1040fe4 pc=0x35870 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1040fec sp=0x1040fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcenable in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 20 gp=0x1100248 m=nil [GC scavenge wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x10417b4 sp=0x10417a0 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goparkunlock(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:408 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.(*scavengerState).park(0xbcb508) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x10417c8 sp=0x10417b4 pc=0x42a70 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.bgscavenge(0x1104000) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x10417e4 sp=0x10417c8 pc=0x431a8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcenable.gowrap2() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x10417ec sp=0x10417e4 pc=0x3581c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10417ec sp=0x10417ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcenable in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 21 gp=0x1100368 m=nil [finalizer wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1041f8c sp=0x1041f78 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.runfinq() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1041fec sp=0x1041f8c pc=0x3465c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1041fec sp=0x1041fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.createfing in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 35 gp=0x11007e8 m=nil [IO wait, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7ec8, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1059d28 sp=0x1059d14 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7eb8, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1059d40 sp=0x1059d28 pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7eb8, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1059d54 sp=0x1059d40 pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x109c158, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1059d68 sp=0x1059d54 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).Accept(0x109c140) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1059db0 sp=0x1059d68 pc=0x10eb44 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*netFD).accept(0x109c140) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1059e18 sp=0x1059db0 pc=0x17e32c Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*TCPListener).accept(0x10b20f0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1059e30 sp=0x1059e18 pc=0x19642c Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*TCPListener).Accept(0x10b20f0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1059e4c sp=0x1059e30 pc=0x195498 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*onceCloseListener).Accept(0x1075760) Jun 14 19:25:47 volumio2 go-librespot[1258]: :1 +0x34 fp=0x1059e64 sp=0x1059e4c pc=0x362680 Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.(*Server).Serve(0x107fc28, {0x7d6e18, 0x10b20f0}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1059efc sp=0x1059e64 pc=0x340d3c Jun 14 19:25:47 volumio2 go-librespot[1258]: net/http.Serve(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/http/server.go:2794 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.(*ConcreteApiServer).serve(0x11222a0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x1059fe4 sp=0x1059efc pc=0x5e8bc0 Jun 14 19:25:47 volumio2 go-librespot[1258]: main.NewApiServer.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x1059fec sp=0x1059fe4 pc=0x5e7a90 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1059fec sp=0x1059fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by main.NewApiServer in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/cmd/daemon/api_server.go:289 +0x310 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 38 gp=0x10846c8 m=nil [IO wait]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7c48, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x14c9e84 sp=0x14c9e70 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7c38, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x14c9e9c sp=0x14c9e84 pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7c38, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x14c9eb0 sp=0x14c9e9c pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x10286f8, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x14c9ec4 sp=0x14c9eb0 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).RawRead(0x10286e0, 0x110c780) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x14c9ef0 sp=0x14c9ec4 pc=0x10f748 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*rawConn).Read(0x1506570, 0x110c780) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x14c9f10 sp=0x14c9ef0 pc=0x190854 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1102870, 0x110c750, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x14c9f40 sp=0x14c9f10 pc=0x4fe72c Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x10c0c38, {0x1300000, 0x10000, 0x10000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x14c9f9c sp=0x14c9f40 pc=0x502714 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).recv4(0x110ac80, 0x10c0c30) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x14c9fe0 sp=0x14c9f9c pc=0x558fa4 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x14c9fec sp=0x14c9fe0 pc=0x558c5c Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x14c9fec sp=0x14c9fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 37 gp=0x1003d48 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x11178d8, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1044f90 sp=0x1044f7c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1044fec sp=0x1044f90 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1044fec sp=0x1044fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 5 gp=0x12fe008 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x102ae28, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045790 sp=0x104577c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10457ec sp=0x1045790 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10457ec sp=0x10457ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 6 gp=0x12fe128 m=nil [GC worker (idle), 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x102ae40, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1045f90 sp=0x1045f7c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1045fec sp=0x1045f90 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1045fec sp=0x1045fec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 7 gp=0x12fe248 m=nil [GC worker (idle)]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736720, 0x11178f0, 0x1a, 0xa, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1046790 sp=0x104677c pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gcBgMarkWorker() Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x10467ec sp=0x1046790 pc=0x38498 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10467ec sp=0x10467ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by runtime.gcBgMarkStartWorkers in goroutine 36 Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 27 gp=0x12fe5a8 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x12186a8 sp=0x1218694 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x12187a4, 0x121876c, 0x0, 0x0, 0x5, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1218748 sp=0x12186a8 pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x11f23c8) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x12187e4 sp=0x1218748 pc=0x46d7fc Jun 14 19:25:47 volumio2 go-librespot[1258]: nhooyr.io/websocket.newConn.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x12187ec sp=0x12187e4 pc=0x46d3f0 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x12187ec sp=0x12187ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by nhooyr.io/websocket.newConn in goroutine 25 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 39 gp=0x1100488 m=nil [IO wait, 11 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736854, 0x756c7ba8, 0x2, 0x2, 0x5) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x121be5c sp=0x121be48 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.netpollblock(0x756c7b98, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x121be74 sp=0x121be5c pc=0x547d4 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.runtime_pollWait(0x756c7b98, 0x72) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x121be88 sp=0x121be74 pc=0x8f388 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).wait(0x1028748, 0x72, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x121be9c sp=0x121be88 pc=0x10a2d8 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*pollDesc).waitRead(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jun 14 19:25:47 volumio2 go-librespot[1258]: internal/poll.(*FD).RawRead(0x1028730, 0x14a1e60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x121bec8 sp=0x121be9c pc=0x10f748 Jun 14 19:25:47 volumio2 go-librespot[1258]: net.(*rawConn).Read(0x1506590, 0x14a1e60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x121bee8 sp=0x121bec8 pc=0x190854 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x11028b0, 0x14a1e30, 0x0) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x121bf18 sp=0x121bee8 pc=0x4fe72c Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jun 14 19:25:47 volumio2 go-librespot[1258]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x10c0c68, {0x14ca000, 0x10000, 0x10000}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x121bf9c sp=0x121bf18 pc=0x50609c Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).recv6(0x110ac80, 0x10c0c60) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x121bfe0 sp=0x121bf9c pc=0x559198 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x121bfec sp=0x121bfe0 pc=0x558c00 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x121bfec sp=0x121bfec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 30 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 71 gp=0x10030e8 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1047674 sp=0x1047660 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.selectgo(0x1047774, 0x104773c, 0x0, 0x0, 0x3, 0x1) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1047714 sp=0x1047674 pc=0x70d80 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x107aa40) Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:181 +0x1fc fp=0x10477e4 sp=0x1047714 pc=0x4cbbd8 Jun 14 19:25:47 volumio2 go-librespot[1258]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:163 +0x28 fp=0x10477ec sp=0x10477e4 pc=0x4cb7b8 Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.goexit({}) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x10477ec sp=0x10477ec pc=0x93c4c Jun 14 19:25:47 volumio2 go-librespot[1258]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jun 14 19:25:47 volumio2 go-librespot[1258]: /src/player/player.go:163 +0x1c8 Jun 14 19:25:47 volumio2 go-librespot[1258]: goroutine 72 gp=0x1003208 m=nil [select, 5 minutes]: Jun 14 19:25:47 volumio2 go-librespot[1258]: runtime.gopark(0x294b7ded, 0x4031436b, 0xab, 0x3f, 0x4031436c) Jun 14 19:25:47 volumio2 go-librespot[1258]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1267854 sp=0x1267840 pc=0x5cfc8 Jun 14 19:25:47 volumio2 go-librespot[1258]: Segmentation fault Jun 14 19:25:47 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=139/n/a Jun 14 19:25:47 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:25:48 volumio2 systemd[1]: dynamicswap.service: Succeeded. Jun 14 19:25:50 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 14 19:25:50 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jun 14 19:25:50 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:25:50 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:25:50 volumio2 go-librespot[3635]: go-librespot daemon starting... Jun 14 19:25:51 volumio2 go-librespot[3635]: time="2025-06-14T19:25:51+03:00" level=fatal msg="failed loading config" error="failed reading configuration file: yaml: unmarshal errors:\n line 1: cannot unmarshal !!str `_inside...` into map[string]interface {}" Jun 14 19:25:51 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 14 19:25:51 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:25:51 volumio2 volumio[3622]: info: ------------------------------------------- Jun 14 19:25:51 volumio2 volumio[3622]: info: ----- Volumio3 ---- Jun 14 19:25:51 volumio2 volumio[3622]: info: ------------------------------------------- Jun 14 19:25:51 volumio2 volumio[3622]: info: ----- System startup ---- Jun 14 19:25:51 volumio2 volumio[3622]: info: ------------------------------------------- Jun 14 19:25:52 volumio2 volumio-remote-updater[531]: [2025-06-14 19:25:52] [connect] Successful connection Jun 14 19:25:52 volumio2 volumio[3622]: info: MYVOLUMIO Environment detected Jun 14 19:25:53 volumio2 volumio[3622]: info: Plugin folders cleanup Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning into folder /volumio/app/plugins/ Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category audio_interface Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category miscellanea Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category music_service Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category plugins.json Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category system_controller Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category user_interface Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning into folder /data/plugins/ Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category music_service Jun 14 19:25:53 volumio2 volumio[3622]: info: Scanning category system_hardware Jun 14 19:25:53 volumio2 volumio[3622]: info: Plugin folders cleanup completed Jun 14 19:25:53 volumio2 volumio[3622]: info: ------------------------------------------- Jun 14 19:25:53 volumio2 volumio[3622]: info: ----- Core plugins startup ---- Jun 14 19:25:53 volumio2 volumio[3622]: info: ------------------------------------------- Jun 14 19:25:53 volumio2 volumio[3622]: info: Loading plugins from folder /volumio/app/plugins/ Jun 14 19:25:53 volumio2 volumio[3622]: info: Adding plugin upnp to MyMusic Plugins Jun 14 19:25:53 volumio2 volumio[3622]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 14 19:25:53 volumio2 volumio[3622]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 14 19:25:53 volumio2 volumio[3622]: info: Loading plugins from folder /data/plugins/ Jun 14 19:25:53 volumio2 volumio[3622]: info: Loading plugin "system"... Jun 14 19:25:53 volumio2 volumio[3622]: info: No id defined. Creating one Jun 14 19:25:53 volumio2 volumio[3622]: info: Loading plugin "appearance"... Jun 14 19:25:54 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 14 19:25:54 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jun 14 19:25:54 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:25:54 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:25:54 volumio2 go-librespot[3655]: go-librespot daemon starting... Jun 14 19:25:54 volumio2 go-librespot[3655]: time="2025-06-14T19:25:54+03:00" level=fatal msg="failed loading config" error="failed reading configuration file: yaml: unmarshal errors:\n line 1: cannot unmarshal !!str `_inside...` into map[string]interface {}" Jun 14 19:25:54 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 14 19:25:54 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:25:55 volumio2 volumio[3622]: info: Loading plugin "network"... Jun 14 19:25:55 volumio2 volumio[3622]: info: Refreshing Cached IP Addresses Jun 14 19:25:55 volumio2 volumio[3622]: info: Loading plugin "services"... Jun 14 19:25:55 volumio2 volumio[3622]: info: Loading plugin "alsa_controller"... Jun 14 19:25:55 volumio2 sudo[3684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 14 19:25:55 volumio2 sudo[3666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 14 19:25:55 volumio2 sudo[3684]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:25:55 volumio2 sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 14 19:25:55 volumio2 sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:25:55 volumio2 sudo[3666]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:25:55 volumio2 sudo[3668]: pam_unix(sudo:session): session closed for user root Jun 14 19:25:55 volumio2 sudo[3666]: pam_unix(sudo:session): session closed for user root Jun 14 19:25:55 volumio2 volumio[3622]: warn: The ALSA output card is not set, defaulting to card [object Object] Jun 14 19:25:55 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 14 19:25:55 volumio2 volumio[3622]: info: Found match in Cards Database: setting mixer PCM for card HDMI Out Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "wizard"... Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "networkfs"... Jun 14 19:25:56 volumio2 volumio[3622]: info: Starting Udev Watcher for removable devices Jun 14 19:25:56 volumio2 volumio[3622]: error: Cannot associate FS Label, not mounting Jun 14 19:25:56 volumio2 volumio[3622]: info: Ignoring mount for partition: volumio Jun 14 19:25:56 volumio2 volumio[3622]: info: Ignoring mount for partition: volumio_data Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "volumio_command_line_client"... Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "upnp"... Jun 14 19:25:56 volumio2 volumio[3622]: info: [1749918356446] Starting Upmpd Daemon Jun 14 19:25:56 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "my_music"... Jun 14 19:25:56 volumio2 volumio[3622]: info: Loading plugin "mpd"... Jun 14 19:25:56 volumio2 sudo[3684]: pam_unix(sudo:session): session closed for user root Jun 14 19:25:57 volumio2 volumio[3622]: info: Loading plugin "upnp_browser"... Jun 14 19:25:57 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 14 19:25:57 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jun 14 19:25:57 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:25:57 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:25:57 volumio2 go-librespot[3703]: go-librespot daemon starting... Jun 14 19:25:57 volumio2 volumio[3622]: info: Loading plugin "alarm-clock"... Jun 14 19:25:57 volumio2 go-librespot[3703]: time="2025-06-14T19:25:57+03:00" level=fatal msg="failed loading config" error="failed reading configuration file: yaml: unmarshal errors:\n line 1: cannot unmarshal !!str `_inside...` into map[string]interface {}" Jun 14 19:25:57 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 14 19:25:57 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "airplay_emulation"... Jun 14 19:25:58 volumio2 volumio[3622]: info: Starting Shairport Sync Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "last_100"... Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "webradio"... Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "i2s_dacs"... Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "volumiodiscovery"... Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** For more information see Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 19:25:58 volumio2 volumio[3622]: *** WARNING *** For more information see Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** For more information see Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 14 19:25:58 volumio2 node[3622]: *** WARNING *** For more information see Jun 14 19:25:58 volumio2 volumio[3622]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 14 19:25:58 volumio2 volumio[3622]: info: Discovery: Started advertising with name: undefined Jun 14 19:25:58 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 14 19:25:58 volumio2 volumio[3622]: info: Loading plugin "spop"... Jun 14 19:26:00 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 14 19:26:00 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jun 14 19:26:00 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:26:01 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:26:01 volumio2 go-librespot[3711]: go-librespot daemon starting... Jun 14 19:26:01 volumio2 go-librespot[3711]: time="2025-06-14T19:26:01+03:00" level=fatal msg="failed loading config" error="failed reading configuration file: yaml: unmarshal errors:\n line 1: cannot unmarshal !!str `_inside...` into map[string]interface {}" Jun 14 19:26:01 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 14 19:26:01 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "outputs"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "albumart"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Plugin example_plugin is not enabled Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "inputs"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "updater_comm"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Plugin mpdemulation is not enabled Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "rest_api"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "websocket"... Jun 14 19:26:01 volumio2 volumio[3622]: info: Starting Socket.io Server version 2.3.0 Jun 14 19:26:01 volumio2 volumio[3622]: info: Loading plugin "pirateaudio"... Jun 14 19:26:01 volumio2 volumio[3622]: Forking 3 albumart workers Jun 14 19:26:02 volumio2 volumio[3622]: info: Applying required configuration parameters for plugin pirateaudio Jun 14 19:26:02 volumio2 volumio[3622]: info: Loading i18n strings for locale en Jun 14 19:26:02 volumio2 volumio[3622]: Updating browse sources language Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::initPlayerControls Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 19:26:02 volumio2 volumio[3622]: Express server listening on port 3000 Jun 14 19:26:02 volumio2 volumio[3622]: [Metrics] WebUI: 12s 100.28ms Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreStateMachine::resetVolumioState Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreStateMachine::getcurrentVolume Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 19:26:02 volumio2 kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm node: bg 1374: bad block bitmap checksum Jun 14 19:26:02 volumio2 kernel: EXT4-fs error (device mmcblk0p3) in ext4_mb_clear_bb:6642: Filesystem failed CRC Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreStateMachine::getcurrentVolume Jun 14 19:26:02 volumio2 volumio[3622]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 19:26:02 volumio2 volumio[3622]: info: Volumio Network Manager: Network status updated: 2 Jun 14 19:26:02 volumio2 volumio[3622]: Starting albumart workers Jun 14 19:26:03 volumio2 volumio[3622]: info: VolumeController:: Volume=96 Mute =false Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::updateTrackBlock Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrackBlock Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 19:26:03 volumio2 volumio[3622]: Starting albumart workers Jun 14 19:26:03 volumio2 volumio[3622]: Starting albumart workers Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:03 volumio2 volumio[3622]: info: Reloading queue from file Jun 14 19:26:03 volumio2 volumio[3622]: info: VolumeController:: Volume=96 Mute =false Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::updateTrackBlock Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrackBlock Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioRetrievevolume Jun 14 19:26:03 volumio2 volumio[3622]: info: Setting Device type: Raspberry PI Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::setRepeat null single undefined Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::setRandom true Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:03 volumio2 volumio[3622]: info: Completed loading Core Plugins Jun 14 19:26:03 volumio2 volumio[3622]: info: Preparing to generate the ALSA configuration file Jun 14 19:26:03 volumio2 volumio[3622]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Jun 14 19:26:03 volumio2 volumio[3622]: info: Discovery: Started advertising with name: undefined Jun 14 19:26:03 volumio2 volumio[3622]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jun 14 19:26:03 volumio2 volumio[3622]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jun 14 19:26:03 volumio2 volumio[3622]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jun 14 19:26:03 volumio2 volumio[3622]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 14 19:26:03 volumio2 volumio[3622]: info: Reading ALSA contributions from plugins. Jun 14 19:26:03 volumio2 volumio[3622]: info: VolumeController:: Volume=96 Mute =false Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:03 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:03 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:03 volumio2 volumio[3622]: info: Asound.conf file written Jun 14 19:26:03 volumio2 sudo[3770]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 14 19:26:03 volumio2 sudo[3770]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:03 volumio2 sudo[3770]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:03 volumio2 kernel: EXT4-fs error (device mmcblk0p3): ext4_validate_block_bitmap:421: comm mv: bg 1361: bad block bitmap checksum Jun 14 19:26:03 volumio2 kernel: EXT4-fs error (device mmcblk0p3) in ext4_mb_clear_bb:6642: Filesystem failed CRC Jun 14 19:26:04 volumio2 volumio[3622]: info: Output device has changed, restarting MPD Jun 14 19:26:04 volumio2 sudo[3776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 19:26:04 volumio2 sudo[3776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:04 volumio2 volumio[3622]: info: Output device has changed, restarting Shairport Sync Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:04 volumio2 sudo[3776]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:04 volumio2 sudo[3779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 19:26:04 volumio2 sudo[3779]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:04 volumio2 volumio[3622]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 19:26:04 volumio2 volumio[3622]: info: ___________ START PLUGINS ___________ Jun 14 19:26:04 volumio2 volumio[3622]: info: ControllerMpd::onStart: Initializing MPD Jun 14 19:26:04 volumio2 volumio[3622]: info: Creating MPD Configuration file Jun 14 19:26:04 volumio2 sudo[3785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 19:26:04 volumio2 sudo[3785]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:04 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 14 19:26:04 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jun 14 19:26:04 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 19:26:04 volumio2 volumio[3622]: info: [1749918364281] CoreMusicLibrary::Adding element Media Servers Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 19:26:04 volumio2 sudo[3785]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:04 volumio2 sudo[3788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 19:26:04 volumio2 sudo[3788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:04 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:26:04 volumio2 go-librespot[3789]: go-librespot daemon starting... Jun 14 19:26:04 volumio2 systemd[1]: Stopping Music Player Daemon... Jun 14 19:26:04 volumio2 go-librespot[3789]: time="2025-06-14T19:26:04+03:00" level=fatal msg="failed loading config" error="failed reading configuration file: yaml: unmarshal errors:\n line 1: cannot unmarshal !!str `_inside...` into map[string]interface {}" Jun 14 19:26:04 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jun 14 19:26:04 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 14 19:26:04 volumio2 volumio[3622]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 19:26:04 volumio2 volumio[3622]: info: [1749918364582] CoreMusicLibrary::Adding element Last_100 Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 14 19:26:04 volumio2 volumio[3622]: info: [1749918364587] CoreMusicLibrary::Adding element Webradio Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 19:26:04 volumio2 volumio[3622]: info: Initializing BBC Radios Jun 14 19:26:04 volumio2 volumio[3622]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:04 volumio2 volumio-remote-updater[531]: [2025-06-14 19:26:04] [connect] Successful connection Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: Creating Spotify config file Jun 14 19:26:04 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:04 volumio2 volumio[3622]: info: Volumio Calling Home Jun 14 19:26:04 volumio2 sudo[3829]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start pirateaudio.service Jun 14 19:26:04 volumio2 sudo[3829]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:05 volumio2 sudo[3829]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:05 volumio2 volumio[3622]: info: Pirate audio service (daemon) started Jun 14 19:26:05 volumio2 volumio[3622]: info: MPD Permissions set Jun 14 19:26:05 volumio2 volumio[3622]: info: MPD Permissions set Jun 14 19:26:05 volumio2 volumio[3622]: info: VolumeController:: Volume=96 Mute =false Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreStateMachine::pushState Jun 14 19:26:05 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::volumioPushState Jun 14 19:26:05 volumio2 volumio-remote-updater[531]: [2025-06-14 19:26:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749918364 101 Jun 14 19:26:05 volumio2 volumio[3622]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 14 19:26:05 volumio2 volumio[3622]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jun 14 19:26:05 volumio2 volumio[3622]: info: Spotify config file written Jun 14 19:26:05 volumio2 volumio[3622]: info: No need to fix Spotify hosts Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:05 volumio2 sudo[3848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 14 19:26:05 volumio2 sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:05 volumio2 systemd[1]: Stopped go-librespot Daemon. Jun 14 19:26:06 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 19:26:06 volumio2 volumio[3622]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 19:26:06 volumio2 systemd[1]: Started go-librespot Daemon. Jun 14 19:26:06 volumio2 go-librespot[3858]: go-librespot daemon starting... Jun 14 19:26:06 volumio2 volumio[3622]: info: CoreCommandRouter::volumioGetState Jun 14 19:26:06 volumio2 volumio[3622]: info: CorePlayQueue::getTrack 0 Jun 14 19:26:06 volumio2 go-librespot[3858]: time="2025-06-14T19:26:06+03:00" level=info msg="running go-librespot 0.2.0" Jun 14 19:26:06 volumio2 volumio[3622]: info: Starting Shairport Sync Jun 14 19:26:06 volumio2 volumio[3622]: info: Starting Shairport Sync Jun 14 19:26:06 volumio2 volumio[3622]: info: Starting Shairport Sync Jun 14 19:26:06 volumio2 sudo[3866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 19:26:06 volumio2 sudo[3866]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:06 volumio2 sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 19:26:06 volumio2 sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:06 volumio2 sudo[3871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 19:26:06 volumio2 sudo[3871]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:06 volumio2 go-librespot[3858]: time="2025-06-14T19:26:06+03:00" level=debug msg="app state loaded" Jun 14 19:26:06 volumio2 volumio[3622]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 19:26:06 volumio2 volumio[3622]: TypeError: Cannot read property 'charAt' of undefined Jun 14 19:26:06 volumio2 volumio[3622]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Jun 14 19:26:06 volumio2 volumio[3622]: at listOnTimeout (internal/timers.js:554:17) Jun 14 19:26:06 volumio2 volumio[3622]: at processTimers (internal/timers.js:497:7) Jun 14 19:26:06 volumio2 volumio[3622]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 19:26:06 volumio2 go-librespot[3858]: time="2025-06-14T19:26:06+03:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 14 19:26:06 volumio2 sudo[3848]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:06 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 14 19:26:06 volumio2 systemd[1]: shairport-sync.service: Succeeded. Jun 14 19:26:06 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 14 19:26:07 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 14 19:26:07 volumio2 sudo[3868]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:07 volumio2 sudo[3871]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:07 volumio2 sudo[3866]: pam_unix(sudo:session): session closed for user root Jun 14 19:26:08 volumio2 go-librespot[3858]: time="2025-06-14T19:26:08+03:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jun 14 19:26:08 volumio2 go-librespot[3858]: time="2025-06-14T19:26:08+03:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jun 14 19:26:08 volumio2 go-librespot[3858]: time="2025-06-14T19:26:08+03:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jun 14 19:26:08 volumio2 go-librespot[3858]: time="2025-06-14T19:26:08+03:00" level=info msg="zeroconf server listening on port 45513" Jun 14 19:26:08 volumio2 sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 19:25 Jun 14 19:26:08 volumio2 sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 19:26:09 volumio2 systemd[1]: mpd.service: Succeeded. Jun 14 19:26:09 volumio2 systemd[1]: Stopped Music Player Daemon. 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 20 Mar 2025 10:16:14 AM CET" VOLUMIO_VERSION="3.796" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2cf33841545f647b04907f8a4bc0a7c7"