Dec 11 08:41:58 volumio kernel: containerd-shim invoked oom-killer: gfp_mask=0x140cca(GFP_HIGHUSER_MOVABLE|__GFP_COMP), order=0, oom_score_adj=-998 Dec 11 08:41:58 volumio kernel: CPU: 3 UID: 0 PID: 8699 Comm: containerd-shim Tainted: G C 6.12.47-v7+ #1904 Dec 11 08:41:58 volumio kernel: Tainted: [C]=CRAP Dec 11 08:41:58 volumio kernel: Hardware name: BCM2835 Dec 11 08:41:58 volumio kernel: Call trace: Dec 11 08:41:58 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Dec 11 08:41:58 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Dec 11 08:41:58 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Dec 11 08:41:58 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Dec 11 08:41:58 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Dec 11 08:41:58 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Dec 11 08:41:58 volumio kernel: __alloc_pages_noprof from __folio_alloc_noprof+0x14/0x20 Dec 11 08:41:58 volumio kernel: __folio_alloc_noprof from __filemap_get_folio+0xec/0x2ec Dec 11 08:41:58 volumio kernel: __filemap_get_folio from filemap_fault+0x6d0/0xc38 Dec 11 08:41:58 volumio kernel: filemap_fault from __do_fault+0x40/0x188 Dec 11 08:41:58 volumio kernel: __do_fault from handle_mm_fault+0x79c/0xfd0 Dec 11 08:41:58 volumio kernel: handle_mm_fault from do_page_fault+0x1bc/0x46c Dec 11 08:41:58 volumio kernel: do_page_fault from do_PrefetchAbort+0x40/0xa0 Dec 11 08:41:58 volumio kernel: do_PrefetchAbort from ret_from_exception+0x0/0x20 Dec 11 08:41:58 volumio kernel: Exception stack(0xb9581fb0 to 0xb9581ff8) Dec 11 08:41:58 volumio kernel: 1fa0: 007851a0 00000101 00000de0 0052cbb0 Dec 11 08:41:58 volumio kernel: 1fc0: 0000000d 00026de0 0052c8b8 0183faa0 00000006 00168a8c 01802c68 00000fff Dec 11 08:41:58 volumio kernel: 1fe0: 00000001 01867bb4 00054ab8 00093a08 80000010 ffffffff Dec 11 08:41:58 volumio kernel: Mem-Info: Dec 11 08:41:58 volumio kernel: active_anon:107269 inactive_anon:74109 isolated_anon:0 active_file:499 inactive_file:321 isolated_file:0 unevictable:4086 dirty:0 writeback:0 slab_reclaimable:7481 slab_unreclaimable:7152 mapped:2167 shmem:5529 pagetables:2055 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:4608 free_pcp:2 free_cma:11 Dec 11 08:41:58 volumio kernel: Node 0 active_anon:429076kB inactive_anon:296436kB active_file:2296kB inactive_file:1084kB unevictable:16344kB isolated(anon):0kB isolated(file):0kB mapped:8668kB dirty:0kB writeback:0kB shmem:22116kB writeback_tmp:0kB kernel_stack:5400kB pagetables:8220kB sec_pagetables:0kB all_unreclaimable? no Dec 11 08:41:58 volumio kernel: DMA free:18432kB boost:12288kB min:28672kB low:32768kB high:36864kB reserved_highatomic:0KB active_anon:429076kB inactive_anon:296436kB active_file:1968kB inactive_file:1208kB unevictable:16344kB writepending:0kB present:917504kB managed:890728kB mlocked:16344kB bounce:0kB free_pcp:8kB local_pcp:0kB free_cma:44kB Dec 11 08:41:58 volumio kernel: lowmem_reserve[]: 0 0 0 Dec 11 08:41:58 volumio kernel: DMA: 569*4kB (UMEC) 489*8kB (UME) 260*16kB (UME) 71*32kB (UME) 32*64kB (UME) 10*128kB (UME) 7*256kB (UM) 3*512kB (U) 0*1024kB 0*2048kB 0*4096kB = 19276kB Dec 11 08:41:58 volumio kernel: 8103 total pagecache pages Dec 11 08:41:58 volumio kernel: 0 pages in swap cache Dec 11 08:41:58 volumio kernel: Free swap = 0kB Dec 11 08:41:58 volumio kernel: Total swap = 0kB Dec 11 08:41:58 volumio kernel: 229376 pages RAM Dec 11 08:41:58 volumio kernel: 0 pages HighMem/MovableOnly Dec 11 08:41:58 volumio kernel: 6694 pages reserved Dec 11 08:41:58 volumio kernel: 16384 pages cma reserved Dec 11 08:41:58 volumio kernel: Tasks state (memory values in pages): Dec 11 08:41:58 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Dec 11 08:41:58 volumio kernel: [ 437] 0 437 6656 806 192 515 99 26624 0 -250 systemd-journal Dec 11 08:41:58 volumio kernel: [ 463] 0 463 5934 802 224 578 0 18432 0 -1000 systemd-udevd Dec 11 08:41:58 volumio kernel: [ 656] 0 656 1959 1075 724 351 0 18432 0 0 haveged Dec 11 08:41:58 volumio kernel: [ 661] 108 661 1731 509 64 445 0 14336 0 0 rpcbind Dec 11 08:41:58 volumio kernel: [ 683] 100 683 602 392 32 360 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 685] 0 685 564 387 32 355 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 686] 100 686 560 283 32 251 0 8192 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 687] 100 687 560 283 32 251 0 8192 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 696] 103 696 1585 543 64 479 0 16384 0 0 avahi-daemon Dec 11 08:41:58 volumio kernel: [ 698] 101 698 1889 559 96 463 0 14336 0 -900 dbus-daemon Dec 11 08:41:58 volumio kernel: [ 704] 0 704 3609 712 128 584 0 20480 0 0 systemd-logind Dec 11 08:41:58 volumio kernel: [ 705] 65534 705 1250 471 32 439 0 14336 0 0 thd Dec 11 08:41:58 volumio kernel: [ 707] 1000 707 11914 1346 224 1122 0 32768 0 0 volumio-remote- Dec 11 08:41:58 volumio kernel: [ 728] 103 728 1545 303 33 270 0 14336 0 0 avahi-daemon Dec 11 08:41:58 volumio kernel: [ 729] 0 729 1449 461 32 429 0 16384 0 0 cron Dec 11 08:41:58 volumio kernel: [ 757] 0 757 3274 641 96 545 0 22528 0 0 wpa_supplicant Dec 11 08:41:58 volumio kernel: [ 803] 100 803 564 264 32 232 0 8192 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 842] 100 842 602 398 32 366 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 843] 0 843 643 453 32 421 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 844] 100 844 560 240 32 208 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 845] 100 845 560 282 32 250 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 868] 0 868 1409 390 32 358 0 14336 0 0 bash Dec 11 08:41:58 volumio kernel: [ 871] 0 871 166594 2902 2650 252 0 98304 0 -999 containerd Dec 11 08:41:58 volumio kernel: [ 872] 0 872 930 486 32 454 0 10240 0 0 ip Dec 11 08:41:58 volumio kernel: [ 873] 0 873 1393 388 32 356 0 12288 0 0 sed Dec 11 08:41:58 volumio kernel: [ 874] 0 874 1409 318 33 285 0 10240 0 0 bash Dec 11 08:41:58 volumio kernel: [ 896] 0 896 520 373 0 373 0 12288 0 0 ifplugd Dec 11 08:41:58 volumio kernel: [ 948] 106 948 4358 4061 2454 1607 0 22528 0 0 ntpd Dec 11 08:41:58 volumio kernel: [ 1069] 100 1069 643 314 65 249 0 10240 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 1228] 0 1228 3390 1318 192 1126 0 20480 0 -1000 sshd Dec 11 08:41:58 volumio kernel: [ 1235] 0 1235 2808 441 64 377 0 16384 0 0 bluetoothd Dec 11 08:41:58 volumio kernel: [ 1239] 0 1239 16457 914 384 460 70 45056 0 0 nmbd Dec 11 08:41:58 volumio kernel: [ 1262] 0 1262 16383 746 406 340 0 38912 0 0 nmbd Dec 11 08:41:58 volumio kernel: [ 1297] 100 1297 564 313 32 281 0 8192 0 0 dhcpcd Dec 11 08:41:58 volumio kernel: [ 1381] 0 1381 11194 557 128 429 0 32768 0 0 bluealsa Dec 11 08:41:58 volumio kernel: [ 1382] 0 1382 178176 4429 4041 388 0 129024 0 -500 dockerd Dec 11 08:41:58 volumio kernel: [ 1384] 1000 1384 71177 21053 20483 570 0 530432 0 0 node Dec 11 08:41:58 volumio kernel: [ 1406] 0 1406 1115 427 0 427 0 14336 0 0 agetty Dec 11 08:41:58 volumio kernel: [ 1421] 0 1421 18061 964 480 439 45 49152 0 0 winbindd Dec 11 08:41:58 volumio kernel: [ 1435] 0 1435 18121 1052 482 527 43 49152 0 0 winbindd Dec 11 08:41:58 volumio kernel: [ 1441] 0 1441 19293 1181 512 475 194 57344 0 0 smbd Dec 11 08:41:58 volumio kernel: [ 1451] 0 1451 18897 947 508 439 0 55296 0 0 smbd-notifyd Dec 11 08:41:58 volumio kernel: [ 1452] 0 1452 18900 842 508 334 0 55296 0 0 cleanupd Dec 11 08:41:58 volumio kernel: [ 1458] 0 1458 18061 849 484 365 0 49152 0 0 winbindd Dec 11 08:41:58 volumio kernel: [ 1710] 0 1710 135125 611 611 0 0 22528 0 -998 containerd-shim Dec 11 08:41:58 volumio kernel: [ 1712] 0 1712 135125 720 720 0 0 22528 0 -998 containerd-shim Dec 11 08:41:58 volumio kernel: [ 1757] 1883 1757 1562 356 356 0 0 16384 0 0 mosquitto Dec 11 08:41:58 volumio kernel: [ 1763] 9001 1763 386 0 0 0 0 10240 0 0 tini Dec 11 08:41:58 volumio kernel: [ 1832] 1000 1832 1400 341 32 309 0 14336 0 0 network_monitor Dec 11 08:41:58 volumio kernel: [ 1882] 9001 1882 138286 104407 104394 13 0 540672 0 0 java Dec 11 08:41:58 volumio kernel: [ 1957] 1000 1957 31771 2240 1747 493 0 108544 0 0 node Dec 11 08:41:58 volumio kernel: [ 1985] 1000 1985 34494 4789 4326 463 0 192512 0 0 node Dec 11 08:41:58 volumio kernel: [ 1986] 1000 1986 35460 5731 5254 477 0 219136 0 0 node Dec 11 08:41:58 volumio kernel: [ 1987] 1000 1987 35499 5486 5001 485 0 202752 0 0 node Dec 11 08:41:58 volumio kernel: [ 2254] 1000 2254 75052 1525 444 1081 0 100352 0 0 upmpdcli Dec 11 08:41:58 volumio kernel: [ 4221] 104 4221 41696 1154 416 738 0 83968 0 0 shairport-sync Dec 11 08:41:58 volumio kernel: [ 4373] 0 4373 1409 420 32 388 0 14336 0 0 mpd_monitor.sh Dec 11 08:41:58 volumio kernel: [ 4604] 1000 4604 515 309 0 309 0 12288 0 0 start-go-libres Dec 11 08:41:58 volumio kernel: [ 4605] 1000 4605 158120 3796 3379 417 0 90112 0 0 go-librespot Dec 11 08:41:58 volumio kernel: [ 6441] 0 6441 515 288 0 288 0 12288 0 0 apt.systemd.dai Dec 11 08:41:58 volumio kernel: [ 6551] 0 6551 515 308 0 308 0 8192 0 0 apt.systemd.dai Dec 11 08:41:58 volumio kernel: [ 6569] 0 6569 14545 1992 1600 392 0 67584 0 0 apt-get Dec 11 08:41:58 volumio kernel: [ 8694] 0 8694 135061 646 646 0 0 22528 0 -998 containerd-shim Dec 11 08:41:58 volumio kernel: [ 8750] 0 8750 175 46 0 46 0 10240 0 0 tini Dec 11 08:41:58 volumio kernel: [ 8781] 0 8781 45059 10731 10693 38 0 350208 0 0 node Dec 11 08:41:58 volumio kernel: [ 12317] 1000 12317 515 290 0 290 0 12288 0 0 sh Dec 11 08:41:58 volumio kernel: [ 12318] 1000 12318 30758 1053 594 459 0 92160 0 0 node Dec 11 08:41:58 volumio kernel: [ 14209] 0 14209 1409 466 32 434 0 14336 0 0 bash Dec 11 08:41:58 volumio kernel: [ 14212] 0 14212 1409 490 32 458 0 12288 0 0 setdatetime-hel Dec 11 08:41:58 volumio kernel: [ 14263] 0 14263 3691 1544 1152 392 0 22528 0 0 ntpq Dec 11 08:41:58 volumio kernel: [ 14264] 0 14264 1210 346 0 346 0 12288 0 0 awk Dec 11 08:41:58 volumio kernel: [ 14268] 0 14268 3354 601 64 537 0 20480 0 0 systemctl Dec 11 08:41:58 volumio kernel: [ 14716] 0 14716 42926 328 224 104 0 104448 0 0 mpd Dec 11 08:41:58 volumio kernel: [ 16097] 0 16097 1571 314 32 282 0 14336 0 0 cron Dec 11 08:41:58 volumio kernel: [ 16111] 0 16111 19293 808 514 294 0 55296 0 0 smbd Dec 11 08:41:58 volumio kernel: [ 16135] 0 16135 16457 632 423 209 0 36864 0 0 nmbd Dec 11 08:41:58 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=containerd.service,mems_allowed=0,global_oom,task_memcg=/,task=java,pid=1882,uid=9001 Dec 11 08:41:58 volumio kernel: Out of memory: Killed process 1882 (java) total-vm:553144kB, anon-rss:417576kB, file-rss:52kB, shmem-rss:0kB, UID:9001 pgtables:528kB oom_score_adj:0 Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:28:04+01:00" level=trace msg="sent dealer ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:28:10+01:00" level=trace msg="received dealer pong" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:28:43+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: WebSocket closed: write timed out: context deadline exceeded" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:28:46+01:00" level=trace msg="sent dealer ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:28:52+01:00" level=warning msg="failed sending dealer ping" error="failed to write msg: failed to write frame: context deadline exceeded" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:02+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:04+01:00" level=error msg="did not receive last pong from dealer, 53s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:26+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:28+01:00" level=error msg="did not receive last pong from dealer, 79s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:29+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:29:58+01:00" level=error msg="did not receive last pong from dealer, 109s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:30:04+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:30:28+01:00" level=error msg="did not receive last pong from dealer, 139s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:30:58+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:30:58+01:00" level=error msg="did not receive last pong from dealer, 169s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:31:21+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:31:26+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:31:27+01:00" level=error msg="did not receive last pong from dealer, 199s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:31:56+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:31:58+01:00" level=error msg="did not receive last pong from dealer, 229s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:32:29+01:00" level=error msg="did not receive last pong from dealer, 260s passed" Dec 11 08:41:58 volumio dockerd[1382]: time="2025-12-11T06:32:47.879769633Z" level=warning msg="Health check for container 741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016 error: timed out starting health check for container 741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016" Dec 11 08:41:58 volumio systemd[1]: docker-741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016.scope: Deactivated successfully. Dec 11 08:41:58 volumio volumio-remote-updater[707]: [2025-12-11 06:42:45] [info] Error getting remote endpoint: asio.system:9 (Bad file descriptor) Dec 11 08:41:58 volumio volumio-remote-updater[707]: [2025-12-11 06:47:35] [error] handle_connect error: Timer Expired Dec 11 08:41:58 volumio volumio-remote-updater[707]: [2025-12-11 07:12:11] [warning] socket cancel failed error: asio.system:9 (Bad file descriptor) Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:01+01:00" level=error msg="did not receive last pong from dealer, 290s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:10+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:36+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:37+01:00" level=error msg="did not receive last pong from dealer, 325s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:49+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:33:59+01:00" level=error msg="did not receive last pong from dealer, 349s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:34:30+01:00" level=error msg="did not receive last pong from dealer, 380s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:34:37+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:34:58+01:00" level=error msg="did not receive last pong from dealer, 410s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:35:25+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:35:27+01:00" level=error msg="did not receive last pong from dealer, 439s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:35:28+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:35:54+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:35:57+01:00" level=error msg="did not receive last pong from dealer, 469s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:36:32+01:00" level=error msg="did not receive last pong from dealer, 500s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:36:58+01:00" level=error msg="did not receive last pong from dealer, 529s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:37:09+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:37:28+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:37:31+01:00" level=error msg="did not receive last pong from dealer, 560s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:37:44+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:38:01+01:00" level=error msg="did not receive last pong from dealer, 590s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:38:29+01:00" level=error msg="did not receive last pong from dealer, 619s passed" Dec 11 08:41:58 volumio systemd[1]: docker-741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016.scope: Consumed 1h 5min 5.214s CPU time. Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:38:56+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:39:01+01:00" level=error msg="did not receive last pong from dealer, 649s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:39:29+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:39:29+01:00" level=error msg="did not receive last pong from dealer, 679s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:40:10+01:00" level=error msg="did not receive last pong from dealer, 713s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:40:15+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:40:37+01:00" level=error msg="did not receive last pong from dealer, 748s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:01+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:04+01:00" level=error msg="did not receive last pong from dealer, 774s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:27+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:28+01:00" level=error msg="did not receive last pong from dealer, 799s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:38+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:41:59+01:00" level=error msg="did not receive last pong from dealer, 830s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:42:37+01:00" level=debug msg="renewing login5 access token" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:42:37+01:00" level=error msg="did not receive last pong from dealer, 865s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:07+01:00" level=error msg="did not receive last pong from dealer, 891s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:28+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:36+01:00" level=error msg="did not receive last pong from dealer, 927s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:44+01:00" level=trace msg="received accesspoint pong ack" Dec 11 08:41:58 volumio systemd[1]: mpd.service: start operation timed out. Terminating. Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:48+01:00" level=error msg="failed reconnecting dealer" error="failed obtaining dealer access token: failed renewing login5 access token: failed requesting login5 endpoint: failed requesting login5: Post \"https://login5.spotify.com/v3/login\": context deadline exceeded (Client.Timeout exceeded while awaiting headers)" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:43:58+01:00" level=error msg="did not receive last pong from dealer, 949s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:44:12+01:00" level=warning msg="unknown dealer request: " Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:44:12+01:00" level=debug msg="dealer recv loop stopped" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:44:22+01:00" level=debug msg="sending successful reply for dealer request" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:45:40+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:48:34+01:00" level=error msg="did not receive last pong ack from accesspoint, 226s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:48:57+01:00" level=trace msg="received accesspoint ping" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:49:36+01:00" level=error msg="did not receive last pong ack from accesspoint, 330s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:51:23+01:00" level=error msg="failed sending Pong packet" error="failed writing packet: write tcp 192.168.2.103:34600->34.158.1.133:80: use of closed network connection" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T07:51:28+01:00" level=error msg="did not receive last pong ack from accesspoint, 453s passed" Dec 11 08:41:58 volumio systemd[1]: mpd.service: Failed with result 'timeout'. Dec 11 08:41:58 volumio systemd[1]: Failed to start mpd.service - Music Player Daemon. Dec 11 08:41:58 volumio systemd[1]: mpd.service: Consumed 19.593s CPU time. Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T08:17:31+01:00" level=error msg="did not receive last pong ack from accesspoint, 1744s passed" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T08:41:58+01:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gue1.spotify.com: i/o timeout" Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T08:41:58+01:00" level=error msg="did not receive last pong ack from accesspoint, 3490s passed" Dec 11 08:41:58 volumio systemctl[14268]: Job for mpd.service failed because a timeout was exceeded. Dec 11 08:41:58 volumio systemctl[14268]: See "systemctl status mpd.service" and "journalctl -xeu mpd.service" for details. Dec 11 08:41:58 volumio go-librespot[4605]: time="2025-12-11T08:41:58+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Dec 11 08:41:59 volumio mpd_monitor.sh[4373]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=debug msg="completed keyexchange" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=debug msg="completed challenge" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=info msg="authenticated AP" username="f9**23" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=debug msg="re-established accesspoint connection" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=trace msg="received accesspoint ping" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 11 08:42:00 volumio go-librespot[4605]: time="2025-12-11T08:42:00+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 11 08:42:00 volumio CRON[16097]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0) Dec 11 08:42:00 volumio CRON[16178]: (root) CMD (cd / && run-parts --report /etc/cron.hourly) Dec 11 08:42:00 volumio CRON[16097]: pam_unix(cron:session): session closed for user root Dec 11 08:42:00 volumio dockerd[1382]: time="2025-12-11T07:42:00.837638964Z" level=error msg="Failed to get event" error="rpc error: code = Unavailable desc = error reading from server: EOF" module=libcontainerd namespace=plugins.moby Dec 11 08:42:00 volumio dockerd[1382]: time="2025-12-11T07:42:00.886369762Z" level=info msg="Waiting for containerd to be ready to restart event processing" module=libcontainerd namespace=plugins.moby Dec 11 08:42:03 volumio containerd[871]: time="2025-12-11T07:42:03.157050481Z" level=info msg="shim disconnected" id=741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016 namespace=moby Dec 11 08:42:03 volumio containerd[871]: time="2025-12-11T07:42:03.158713968Z" level=info msg="cleaning up after shim disconnected" id=741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016 namespace=moby Dec 11 08:42:03 volumio containerd[871]: time="2025-12-11T07:42:03.158870582Z" level=info msg="cleaning up dead shim" id=741c365c006744bdf7ccdd55c6d1ba50748dbd81a11a271a96667bbd0e49f016 namespace=moby Dec 11 08:42:03 volumio sudo[16218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-11 08:41' Dec 11 08:42:03 volumio sudo[16218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"