Dec 13 15:17:00 volumio upmpdcli[2397]: writing RSA key Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:17:00 volumio volumio[1408]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 13 15:17:00 volumio volumio[1408]: info: Received Get System Version Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 15:17:00 volumio volumio[1408]: info: Received Get System Info Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:17:00 volumio volumio[1408]: info: Discovery: Getting this device information Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::volumioGetState Dec 13 15:17:00 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::volumioGetBrowseSources Dec 13 15:17:00 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 13 15:17:01 volumio volumio[1408]: error: MyVolumio Plugin failed to authenticate in a timely fashion Dec 13 15:17:01 volumio volumio[1408]: info: Completed starting MyVolumio Plugin Dec 13 15:17:01 volumio volumio[1408]: [Metrics] CommandRouter: 38s 70.08ms Dec 13 15:17:01 volumio volumio[1408]: info: CoreCommandRouter::volumiosetStartupVolume Dec 13 15:17:01 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 13 15:17:01 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 13 15:17:01 volumio volumio[1408]: info: CoreCommandRouter::Close All Modals sent Dec 13 15:17:01 volumio volumio[1408]: info: CoreCommandRouter::Close All Modals sent Dec 13 15:17:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 13 15:17:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 13 15:17:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 13 15:17:03 volumio volumio[1408]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 13 15:17:07 volumio volumio[1408]: info: CoreCommandRouter::volumioGetState Dec 13 15:17:07 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:17:08 volumio volumio[1408]: info: BOOT COMPLETED Dec 13 15:17:08 volumio volumio[1408]: info: mpdhttpout ---Boot completed detected! Patching mpd now! Dec 13 15:17:08 volumio volumio[1408]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Dec 13 15:17:08 volumio volumio[1408]: info: CoreCommandRouter::volumioGetState Dec 13 15:17:08 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:17:08 volumio volumio[1408]: info: CoreCommandRouter::volumioPause Dec 13 15:17:08 volumio volumio[1408]: info: CoreStateMachine::pause Dec 13 15:17:08 volumio volumio[1408]: info: mpdhttpout --- Volumio set on pause Dec 13 15:17:09 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 15:17:09 volumio volumio[1408]: info: Listing playlists Dec 13 15:17:09 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 15:17:09 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 15:17:09 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 15:17:09 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:17:10 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 13 15:17:10 volumio sudo[2423]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 13 15:17:10 volumio sudo[2423]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:17:10 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 13 15:17:10 volumio systemd[1]: musicservicesshield.service: Deactivated successfully. Dec 13 15:17:10 volumio systemd[1]: Stopped musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 13 15:17:10 volumio systemd[1]: Stopping musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 13 15:17:10 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 13 15:17:15 volumio startx[1837]: (EE) event5 - wch.cn USB2IIC_CTP_CONTROL: client bug: event processing lagging behind by 76ms, your system is too slow Dec 13 15:17:15 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 13 15:17:15 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 15:17:15 volumio systemd[1]: mpd.service: Consumed 1.752s CPU time. Dec 13 15:17:15 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 13 15:17:15 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 15:17:15 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 15:17:15 volumio mpd_monitor.sh[2466]: MPD error: Connection reset by peer Dec 13 15:17:15 volumio mpd_monitor.sh[2251]: MPD Monitor Service: MPD Appears to be inactive, restarting Dec 13 15:17:15 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 15:17:15 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 15:17:15 volumio mpd_monitor.sh[2477]: mpd: no process found Dec 13 15:17:16 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 13 15:17:16 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 13 15:17:16 volumio systemd[1]: setdatetime-helper.service: Consumed 1.000s CPU time. Dec 13 15:17:16 volumio sudo[2476]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 15:17:16 volumio sudo[2476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 15:17:16 volumio sudo[2476]: pam_unix(sudo:session): session closed for user root Dec 13 15:17:16 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 13 15:17:16 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 13 15:17:16 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 13 15:17:16 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 13 15:17:16 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 13 15:17:16 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 13 15:17:16 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 13 15:17:16 volumio sudo[2484]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 13 15:17:16 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 13 15:17:16 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Dec 13 15:17:21 volumio mpd[2486]: 2025-12-13T15:17:21 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 13 15:17:21 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 13 15:17:21 volumio sudo[2423]: pam_unix(sudo:session): session closed for user root Dec 13 15:17:21 volumio systemd[1]: Starting musicservicesshield.service - Shield Volumio Music Services in the User CPU Set... Dec 13 15:17:21 volumio mpd_monitor.sh[2251]: MPD Monitor Service: MPD restarted due to no mpc output. Dec 13 15:17:21 volumio volumio[1408]: error: updateQueue error: null Dec 13 15:17:22 volumio bash[2504]: cset: --> shielding system active with Dec 13 15:17:22 volumio bash[2504]: cset: "system" cpuset of CPUSPEC(1-3) with 254 tasks running Dec 13 15:17:22 volumio bash[2504]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Dec 13 15:17:22 volumio systemd[1]: Finished musicservicesshield.service - Shield Volumio Music Services in the User CPU Set. Dec 13 15:17:24 volumio sudo[2520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 13 15:17:24 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 13 15:17:24 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 13 15:17:24 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 13 15:17:24 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 13 15:17:24 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 13 15:17:25 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Dec 13 15:17:25 volumio volumio[1408]: info: Successfully started MPD Monitor Dec 13 15:17:25 volumio mpd_monitor.sh[2522]: MPD Monitor Service: Starting MPD Monitor Service Dec 13 15:17:30 volumio kernel: ThreadPoolForeg invoked oom-killer: gfp_mask=0x140dca(GFP_HIGHUSER_MOVABLE|__GFP_COMP|__GFP_ZERO), order=0, oom_score_adj=300 Dec 13 15:17:30 volumio kernel: CPU: 3 UID: 1000 PID: 2247 Comm: ThreadPoolForeg Tainted: G C 6.12.47-v8+ #1904 Dec 13 15:17:30 volumio kernel: Tainted: [C]=CRAP Dec 13 15:17:30 volumio kernel: Hardware name: Raspberry Pi 5 Model B Rev 1.0 (DT) Dec 13 15:17:30 volumio kernel: Call trace: Dec 13 15:17:30 volumio kernel: dump_backtrace+0x9c/0x100 Dec 13 15:17:30 volumio kernel: show_stack+0x20/0x38 Dec 13 15:17:30 volumio kernel: dump_stack_lvl+0x78/0x90 Dec 13 15:17:30 volumio kernel: dump_stack+0x18/0x28 Dec 13 15:17:30 volumio kernel: dump_header+0x48/0x1b0 Dec 13 15:17:30 volumio kernel: oom_kill_process+0x138/0x368 Dec 13 15:17:30 volumio kernel: out_of_memory+0xe4/0x598 Dec 13 15:17:30 volumio kernel: __alloc_pages_noprof+0xad8/0xdf0 Dec 13 15:17:30 volumio kernel: alloc_pages_mpol_noprof+0x5c/0x158 Dec 13 15:17:30 volumio kernel: vma_alloc_folio_noprof+0x80/0xe0 Dec 13 15:17:30 volumio kernel: vma_alloc_zeroed_movable_folio+0x44/0x58 Dec 13 15:17:30 volumio kernel: __handle_mm_fault+0x5a4/0xcb8 Dec 13 15:17:30 volumio kernel: handle_mm_fault+0xe0/0x2c8 Dec 13 15:17:30 volumio kernel: do_page_fault+0x108/0x578 Dec 13 15:17:30 volumio kernel: do_translation_fault+0xb4/0xd8 Dec 13 15:17:30 volumio kernel: do_mem_abort+0x4c/0xa8 Dec 13 15:17:30 volumio kernel: el0_da+0x2c/0xa0 Dec 13 15:17:30 volumio kernel: el0t_32_sync_handler+0x11c/0x148 Dec 13 15:17:30 volumio kernel: el0t_32_sync+0x194/0x198 Dec 13 15:17:30 volumio kernel: Mem-Info: Dec 13 15:17:30 volumio kernel: active_anon:65554 inactive_anon:41634 isolated_anon:0 active_file:20450 inactive_file:54587 isolated_file:0 unevictable:5766 dirty:121 writeback:0 slab_reclaimable:8757 slab_unreclaimable:10215 mapped:24314 shmem:17485 pagetables:3357 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:794680 free_pcp:123 free_cma:12148 Dec 13 15:17:30 volumio kernel: Node 0 active_anon:244352kB inactive_anon:166536kB active_file:132kB inactive_file:636kB unevictable:16124kB isolated(anon):0kB isolated(file):0kB mapped:25932kB dirty:408kB writeback:0kB shmem:65624kB writeback_tmp:0kB kernel_stack:4712kB pagetables:11972kB sec_pagetables:0kB all_unreclaimable? no Dec 13 15:17:30 volumio kernel: Node 0 DMA free:3448kB boost:0kB min:1972kB low:2464kB high:2956kB reserved_highatomic:0KB active_anon:244352kB inactive_anon:166536kB active_file:340kB inactive_file:380kB unevictable:16124kB writepending:408kB present:524288kB managed:492956kB mlocked:9432kB bounce:0kB free_pcp:492kB local_pcp:120kB free_cma:0kB Dec 13 15:17:30 volumio kernel: lowmem_reserve[]: 0 0 0 0 Dec 13 15:17:30 volumio kernel: Node 0 DMA: 54*4kB (UME) 52*8kB (UE) 56*16kB (UE) 20*32kB (UE) 2*64kB (UE) 1*128kB (E) 0*256kB 0*512kB 1*1024kB (E) 0*2048kB 0*4096kB = 3448kB Dec 13 15:17:30 volumio kernel: 94124 total pagecache pages Dec 13 15:17:30 volumio kernel: 0 pages in swap cache Dec 13 15:17:30 volumio kernel: Free swap = 0kB Dec 13 15:17:30 volumio kernel: Total swap = 0kB Dec 13 15:17:30 volumio kernel: 1046528 pages RAM Dec 13 15:17:30 volumio kernel: 0 pages HighMem/MovableOnly Dec 13 15:17:30 volumio kernel: 24363 pages reserved Dec 13 15:17:30 volumio kernel: 16384 pages cma reserved Dec 13 15:17:30 volumio kernel: Tasks state (memory values in pages): Dec 13 15:17:30 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Dec 13 15:17:30 volumio kernel: [ 528] 0 528 4660 1836 160 1420 256 61440 0 -250 systemd-journal Dec 13 15:17:30 volumio kernel: [ 552] 0 552 6045 1275 288 987 0 57344 0 -1000 systemd-udevd Dec 13 15:17:30 volumio kernel: [ 916] 0 916 1995 1155 774 381 0 45056 0 0 haveged Dec 13 15:17:30 volumio kernel: [ 923] 108 923 1730 813 64 749 0 40960 0 0 rpcbind Dec 13 15:17:30 volumio kernel: [ 951] 100 951 602 470 64 406 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 952] 0 952 563 402 32 370 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 953] 100 953 559 279 32 247 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 954] 100 954 559 247 32 215 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1026] 103 1026 1584 819 64 755 0 32768 0 0 avahi-daemon Dec 13 15:17:30 volumio kernel: [ 1027] 0 1027 2808 1290 64 1226 0 49152 0 0 bluetoothd Dec 13 15:17:30 volumio kernel: [ 1028] 101 1028 1886 914 96 818 0 40960 0 -900 dbus-daemon Dec 13 15:17:30 volumio kernel: [ 1043] 0 1043 7698 1601 148 1453 0 69632 0 0 systemd-logind Dec 13 15:17:30 volumio kernel: [ 1045] 65534 1045 1249 594 32 562 0 40960 0 0 thd Dec 13 15:17:30 volumio kernel: [ 1047] 1000 1047 11913 2380 224 2156 0 90112 0 0 volumio-remote- Dec 13 15:17:30 volumio kernel: [ 1049] 0 1049 32079 8156 1254 6902 0 262144 0 0 node Dec 13 15:17:30 volumio kernel: [ 1068] 103 1068 1544 370 33 337 0 32768 0 0 avahi-daemon Dec 13 15:17:30 volumio kernel: [ 1114] 100 1114 602 454 64 390 0 32768 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1115] 0 1115 643 457 64 393 0 32768 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1116] 100 1116 559 274 32 242 0 32768 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1117] 100 1117 559 242 32 210 0 32768 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1202] 0 1202 3273 1248 96 1152 0 53248 0 0 wpa_supplicant Dec 13 15:17:30 volumio kernel: [ 1205] 0 1205 11193 1761 96 1665 0 73728 0 0 bluealsa Dec 13 15:17:30 volumio kernel: [ 1249] 106 1249 4360 4066 2454 1612 0 53248 0 0 ntpd Dec 13 15:17:30 volumio kernel: [ 1255] 0 1255 1406 675 32 643 0 36864 0 0 bash Dec 13 15:17:30 volumio kernel: [ 1257] 0 1257 929 597 32 565 0 32768 0 0 ip Dec 13 15:17:30 volumio kernel: [ 1258] 0 1258 1392 452 0 452 0 36864 0 0 sed Dec 13 15:17:30 volumio kernel: [ 1259] 0 1259 1406 416 32 384 0 36864 0 0 bash Dec 13 15:17:30 volumio kernel: [ 1273] 0 1273 519 324 0 324 0 32768 0 0 ifplugd Dec 13 15:17:30 volumio kernel: [ 1279] 0 1279 3389 1902 160 1742 0 53248 0 -1000 sshd Dec 13 15:17:30 volumio kernel: [ 1293] 0 1293 9839 1483 128 1355 0 73728 0 0 upowerd Dec 13 15:17:30 volumio kernel: [ 1321] 0 1321 16454 2701 384 2248 69 98304 0 0 nmbd Dec 13 15:17:30 volumio kernel: [ 1335] 0 1335 16380 1023 406 617 0 86016 0 0 nmbd Dec 13 15:17:30 volumio kernel: [ 1408] 1000 1408 66212 27496 19483 8013 0 1126400 0 0 node Dec 13 15:17:30 volumio kernel: [ 1429] 0 1429 1125 454 0 454 0 32768 0 0 agetty Dec 13 15:17:30 volumio kernel: [ 1454] 1000 1454 1397 648 32 616 0 36864 0 0 network_monitor Dec 13 15:17:30 volumio kernel: [ 1487] 100 1487 643 339 66 273 0 32768 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1488] 100 1488 563 308 32 276 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1489] 1000 1489 31901 7961 1234 6727 0 233472 0 0 node Dec 13 15:17:30 volumio kernel: [ 1499] 1000 1499 33051 9300 2220 7080 0 311296 0 0 node Dec 13 15:17:30 volumio kernel: [ 1500] 1000 1500 33000 9339 2220 7119 0 307200 0 0 node Dec 13 15:17:30 volumio kernel: [ 1504] 1000 1504 32990 9334 2259 7075 0 311296 0 0 node Dec 13 15:17:30 volumio kernel: [ 1794] 1000 1794 514 284 0 284 0 32768 0 0 startx Dec 13 15:17:30 volumio kernel: [ 1803] 104 1803 37563 2872 416 2456 0 143360 0 0 shairport-sync Dec 13 15:17:30 volumio kernel: [ 1836] 1000 1836 1000 561 32 529 0 36864 0 0 xinit Dec 13 15:17:30 volumio kernel: [ 1837] 1000 1837 62128 8995 5440 2403 1152 385024 0 0 Xorg Dec 13 15:17:30 volumio kernel: [ 1886] 0 1886 18060 2905 480 2380 45 110592 0 0 winbindd Dec 13 15:17:30 volumio kernel: [ 1901] 0 1901 18120 2543 481 2019 43 114688 0 0 winbindd Dec 13 15:17:30 volumio kernel: [ 1908] 0 1908 19289 3236 480 2562 194 122880 0 0 smbd Dec 13 15:17:30 volumio kernel: [ 1910] 0 1910 17912 2345 455 1848 42 106496 0 0 samba-dcerpcd Dec 13 15:17:30 volumio kernel: [ 1959] 100 1959 563 309 32 277 0 28672 0 0 dhcpcd Dec 13 15:17:30 volumio kernel: [ 1960] 0 1960 18896 2155 503 1652 0 118784 0 0 smbd-notifyd Dec 13 15:17:30 volumio kernel: [ 1962] 0 1962 18896 1168 503 665 0 118784 0 0 cleanupd Dec 13 15:17:30 volumio kernel: [ 1986] 1000 1986 1397 645 32 613 0 32768 0 0 volumiokiosk.sh Dec 13 15:17:30 volumio kernel: [ 1987] 0 1987 19304 3088 544 2499 45 126976 0 0 rpcd_lsad Dec 13 15:17:30 volumio kernel: [ 2006] 0 2006 19192 2999 512 2442 45 126976 0 0 rpcd_lsad Dec 13 15:17:30 volumio kernel: [ 2007] 0 2007 18060 2010 483 1527 0 106496 0 0 winbindd Dec 13 15:17:30 volumio kernel: [ 2046] 1000 2046 1502 330 95 235 0 36864 0 0 ssh-agent Dec 13 15:17:30 volumio kernel: [ 2053] 1000 2053 24587 2288 736 1552 0 118784 0 0 openbox Dec 13 15:17:30 volumio kernel: [ 2054] 1000 2054 200355 11575 8303 2922 350 983040 0 0 chromium Dec 13 15:17:30 volumio kernel: [ 2110] 1000 2110 3821 450 64 386 0 40960 0 0 chrome_crashpad Dec 13 15:17:30 volumio kernel: [ 2114] 1000 2114 1768 360 32 328 0 36864 0 0 chrome_crashpad Dec 13 15:17:30 volumio kernel: [ 2116] 1000 2116 116096 6210 4096 2114 0 593920 0 0 chromium Dec 13 15:17:30 volumio kernel: [ 2117] 1000 2117 116094 6239 4096 2143 0 606208 0 0 chromium Dec 13 15:17:30 volumio kernel: [ 2122] 1000 2122 116100 4355 4129 226 0 274432 0 0 chromium Dec 13 15:17:30 volumio kernel: [ 2159] 1000 2159 144232 9443 5024 1652 2767 569344 0 200 chromium Dec 13 15:17:30 volumio kernel: [ 2163] 1000 2163 129546 7156 4714 2336 106 704512 0 200 chromium Dec 13 15:17:30 volumio kernel: [ 2185] 1000 2185 132273 5626 4365 1261 0 483328 0 200 chromium Dec 13 15:17:30 volumio kernel: [ 2238] 1000 2238 136655 6408 5172 1074 162 634880 0 300 chromium Dec 13 15:17:30 volumio kernel: [ 2239] 1000 2239 177221 26681 22028 1185 3468 1245184 0 300 chromium Dec 13 15:17:30 volumio kernel: [ 2335] 1000 2335 36120 3013 352 2661 0 143360 0 0 upmpdcli Dec 13 15:17:30 volumio kernel: [ 2439] 1000 2439 135785 6167 4835 1288 44 606208 0 300 chromium Dec 13 15:17:30 volumio kernel: [ 2486] 102 2486 110352 8439 4032 4407 0 602112 0 0 mpd Dec 13 15:17:30 volumio kernel: [ 2518] 1000 2518 1114 370 0 370 0 36864 0 0 sleep Dec 13 15:17:30 volumio kernel: [ 2522] 0 2522 1406 684 32 652 0 40960 0 0 mpd_monitor.sh Dec 13 15:17:30 volumio kernel: [ 2525] 0 2525 1114 358 0 358 0 36864 0 0 sleep Dec 13 15:17:30 volumio kernel: oom-kill:constraint=CONSTRAINT_CPUSET,nodemask=(null),cpuset=system,mems_allowed=0,global_oom,task_memcg=/,task=chromium,pid=2239,uid=1000 Dec 13 15:17:30 volumio kernel: Out of memory: Killed process 2239 (chromium) total-vm:708884kB, anon-rss:88112kB, file-rss:4740kB, shmem-rss:13872kB, UID:1000 pgtables:1216kB oom_score_adj:300 Dec 13 15:17:43 volumio volumio[1408]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::volumioGetState Dec 13 15:17:44 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 13 15:17:44 volumio volumio[1408]: info: Listing playlists Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 13 15:17:44 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 13 15:17:48 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 13 15:17:56 volumio startx[1837]: (EE) event5 - wch.cn USB2IIC_CTP_CONTROL: client bug: event processing lagging behind by 44ms, your system is too slow Dec 13 15:18:02 volumio volumio[1408]: info: Received Get System Info Dec 13 15:18:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 13 15:18:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 13 15:18:02 volumio volumio[1408]: info: Discovery: Getting this device information Dec 13 15:18:02 volumio volumio[1408]: info: CoreCommandRouter::volumioGetState Dec 13 15:18:02 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:18:02 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 13 15:18:15 volumio volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 15:18:15 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 15:18:15 volumio volumio[1408]: info: CoreCommandRouter::volumioGetQueue Dec 13 15:18:15 volumio volumio[1408]: info: CoreStateMachine::getQueue Dec 13 15:18:15 volumio volumio[1408]: info: CorePlayQueue::getQueue Dec 13 15:18:17 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 13 15:18:17 volumio volumio[1408]: info: Preload queue cleared Dec 13 15:18:20 volumio volumio[1408]: info: Preload queue cleared Dec 13 15:18:20 volumio volumio[1408]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::ClearQueue Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::stop Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::clearPlayQueue Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::saveQueue Dec 13 15:18:20 volumio volumio[1408]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::addQueueItems Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::addQueueItems Dec 13 15:18:20 volumio volumio[1408]: info: Preload queue cleared Dec 13 15:18:20 volumio volumio[1408]: info: Adding Item to queue: kradio/mbc Dec 13 15:18:20 volumio volumio[1408]: info: Exploding uri kradio/mbc in service personal_radio Dec 13 15:18:20 volumio volumio[1408]: info: CoreCommandRouter::volumioPushQueue Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::saveQueue Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::updateTrackBlock Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::getTrackBlock Dec 13 15:18:20 volumio volumio[1408]: info: CoreCommandRouter::volumioPlay Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::play index 0 Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::stop Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::play index undefined Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:18:20 volumio volumio[1408]: info: CoreStateMachine::startPlaybackTimer Dec 13 15:18:20 volumio volumio[1408]: info: CorePlayQueue::getTrack 0 Dec 13 15:18:20 volumio volumio[1408]: verbose: ControllerMpd::sendMpdCommand stop Dec 13 15:18:20 volumio volumio[1408]: info: sendMpdCommand stop took 48 milliseconds Dec 13 15:18:20 volumio volumio[1408]: verbose: ControllerMpd::sendMpdCommand clear Dec 13 15:18:20 volumio volumio[1408]: info: Dec 13 15:18:20 volumio volumio[1408]: ---------------------------- MPD announces system playlist update Dec 13 15:18:20 volumio volumio[1408]: info: Ignoring MPD Status Update Dec 13 15:18:20 volumio volumio[1408]: info: sendMpdCommand clear took 2 milliseconds Dec 13 15:18:20 volumio volumio[1408]: verbose: ControllerMpd::sendMpdCommand add "undefined" Dec 13 15:18:20 volumio volumio[1408]: error: updateQueue error: null Dec 13 15:18:20 volumio volumio[1408]: info: ------------------------------ 2ms Dec 13 15:18:20 volumio volumio[1408]: error: [ControllerPersonalRadio::clearAddPlayTrack] Error= [50@0] {add} No such directory {"stack":"Error: [50@0] {add} No such directory\n at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17)\n at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)\n at Socket.emit (node:events:514:28)\n at addChunk (node:internal/streams/readable:343:12)\n at readableAddChunk (node:internal/streams/readable:312:11)\n at Readable.push (node:internal/streams/readable:253:10)\n at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)"} Dec 13 15:18:21 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 13 15:18:21 volumio volumio[1408]: info: CoreCommandRouter::volumioGetVisibleSources Dec 13 15:18:21 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 13 15:18:21 volumio volumio[1408]: info: CoreCommandRouter::volumioGetQueue Dec 13 15:18:21 volumio volumio[1408]: info: CoreStateMachine::getQueue Dec 13 15:18:21 volumio volumio[1408]: info: CorePlayQueue::getQueue Dec 13 15:18:21 volumio volumio[1408]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Dec 13 15:18:21 volumio volumio[1408]: info: Preload queue cleared Dec 13 15:18:21 volumio volumio[1408]: info: Preloading song: webmbc/0 Dec 13 15:18:21 volumio volumio[1408]: info: Preloading song: webmbc/1 Dec 13 15:18:21 volumio volumio[1408]: info: Preloading song: webmbc/2 Dec 13 15:18:21 volumio volumio[1408]: info: Exploding uri webmbc/0 in service personal_radio Dec 13 15:18:21 volumio volumio[1408]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:18:21 volumio volumio[1408]: TypeError: Cannot read properties of null (reading 'startsWith') Dec 13 15:18:21 volumio volumio[1408]: at /data/plugins/music_service/personal_radio/index.js:675:41 Dec 13 15:18:21 volumio volumio[1408]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Dec 13 15:18:21 volumio volumio[1408]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 13 15:18:23 volumio sudo[2654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-13 15:17' Dec 13 15:18:23 volumio sudo[2654]: 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"