Dec 26 10:45:19 volumio sshd[5193]: Accepted password for volumio from 192.168.178.58 port 53796 ssh2 Dec 26 10:45:19 volumio sshd[5193]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 26 10:45:19 volumio systemd-logind[768]: New session 5 of user volumio. Dec 26 10:45:19 volumio systemd[1]: Started session-5.scope - Session 5 of User volumio. Dec 26 10:45:19 volumio sshd[5193]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 26 10:45:35 volumio sshd[5095]: pam_unix(sshd:session): session closed for user volumio Dec 26 10:45:35 volumio systemd[1]: session-4.scope: Deactivated successfully. Dec 26 10:45:35 volumio systemd[1]: session-4.scope: Consumed 2.811s CPU time. Dec 26 10:45:35 volumio systemd-logind[768]: Session 4 logged out. Waiting for processes to exit. Dec 26 10:45:35 volumio systemd-logind[768]: Removed session 4. Dec 26 10:45:35 volumio sshd[5096]: pam_unix(sshd:session): session closed for user volumio Dec 26 10:45:35 volumio systemd[1]: session-3.scope: Deactivated successfully. Dec 26 10:45:35 volumio systemd[1]: session-3.scope: Consumed 3.146s CPU time. Dec 26 10:45:35 volumio systemd-logind[768]: Session 3 logged out. Waiting for processes to exit. Dec 26 10:45:35 volumio systemd-logind[768]: Removed session 3. Dec 26 10:45:42 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 26 10:45:43 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 26 10:45:43 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 26 10:46:08 volumio kernel: mpd_monitor.sh invoked oom-killer: gfp_mask=0x440dc0(GFP_KERNEL_ACCOUNT|__GFP_COMP|__GFP_ZERO), order=0, oom_score_adj=0 Dec 26 10:46:08 volumio kernel: CPU: 2 UID: 0 PID: 1854 Comm: mpd_monitor.sh Tainted: G WC 6.12.47-v7l+ #1904 Dec 26 10:46:08 volumio kernel: Tainted: [W]=WARN, [C]=CRAP Dec 26 10:46:08 volumio kernel: Hardware name: BCM2711 Dec 26 10:46:08 volumio kernel: Call trace: Dec 26 10:46:08 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Dec 26 10:46:08 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Dec 26 10:46:08 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Dec 26 10:46:08 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Dec 26 10:46:08 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Dec 26 10:46:08 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Dec 26 10:46:08 volumio kernel: __alloc_pages_noprof from __pmd_alloc+0x44/0x1dc Dec 26 10:46:08 volumio kernel: __pmd_alloc from pgd_alloc+0x250/0x280 Dec 26 10:46:08 volumio kernel: pgd_alloc from mm_init+0xf4/0x264 Dec 26 10:46:08 volumio kernel: mm_init from copy_process+0xd74/0x1dd0 Dec 26 10:46:08 volumio kernel: copy_process from kernel_clone+0x9c/0x37c Dec 26 10:46:08 volumio kernel: kernel_clone from sys_clone+0x78/0xa0 Dec 26 10:46:08 volumio kernel: sys_clone from ret_fast_syscall+0x0/0x5c Dec 26 10:46:08 volumio kernel: Exception stack(0xf10f9fa8 to 0xf10f9ff0) Dec 26 10:46:08 volumio kernel: 9fa0: b6fa53e8 00000000 01200011 00000000 00000000 00000000 Dec 26 10:46:08 volumio kernel: 9fc0: b6fa53e8 00000000 00000001 00000078 00116188 00000004 001145b8 00000000 Dec 26 10:46:08 volumio kernel: 9fe0: b6fa5880 bef8c084 b6fa5380 b6eb638c Dec 26 10:46:08 volumio kernel: Mem-Info: Dec 26 10:46:08 volumio kernel: active_anon:1744 inactive_anon:87931 isolated_anon:0 active_file:68319 inactive_file:133422 isolated_file:0 unevictable:5683 dirty:9194 writeback:0 slab_reclaimable:11373 slab_unreclaimable:6718 mapped:79655 shmem:7034 pagetables:3013 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:1681167 free_pcp:0 free_cma:126609 Dec 26 10:46:08 volumio kernel: Node 0 active_anon:6976kB inactive_anon:351724kB active_file:273276kB inactive_file:533688kB unevictable:22732kB isolated(anon):0kB isolated(file):0kB mapped:318620kB dirty:36776kB writeback:0kB shmem:28136kB writeback_tmp:0kB kernel_stack:3248kB pagetables:12052kB sec_pagetables:0kB all_unreclaimable? no Dec 26 10:46:08 volumio kernel: DMA free:529172kB boost:36864kB min:53248kB low:57344kB high:61440kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:1244kB inactive_file:296kB unevictable:0kB writepending:140kB present:786432kB managed:626192kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:506436kB Dec 26 10:46:08 volumio kernel: lowmem_reserve[]: 0 0 7232 7232 Dec 26 10:46:08 volumio kernel: DMA: 484*4kB (UEC) 213*8kB (UEC) 158*16kB (UEC) 52*32kB (UEC) 34*64kB (UMEC) 39*128kB (UMEC) 9*256kB (UC) 12*512kB (UMEC) 6*1024kB (UMEC) 2*2048kB (C) 121*4096kB (C) = 529304kB Dec 26 10:46:08 volumio kernel: 210246 total pagecache pages Dec 26 10:46:08 volumio kernel: 0 pages in swap cache Dec 26 10:46:08 volumio kernel: Free swap = 0kB Dec 26 10:46:08 volumio kernel: Total swap = 0kB Dec 26 10:46:08 volumio kernel: 2048000 pages RAM Dec 26 10:46:08 volumio kernel: 1851392 pages HighMem/MovableOnly Dec 26 10:46:08 volumio kernel: 40060 pages reserved Dec 26 10:46:08 volumio kernel: 131072 pages cma reserved Dec 26 10:46:08 volumio kernel: Tasks state (memory values in pages): Dec 26 10:46:08 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Dec 26 10:46:08 volumio kernel: [ 455] 0 455 7580 2332 160 1436 736 65536 0 -250 systemd-journal Dec 26 10:46:08 volumio kernel: [ 481] 0 481 5973 1227 224 1003 0 57344 0 -1000 systemd-udevd Dec 26 10:46:08 volumio kernel: [ 715] 0 715 1959 1081 738 343 0 36864 0 0 haveged Dec 26 10:46:08 volumio kernel: [ 719] 108 719 1731 762 64 698 0 36864 0 0 rpcbind Dec 26 10:46:08 volumio kernel: [ 737] 100 737 603 428 64 364 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 738] 0 738 564 413 32 381 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 739] 100 739 560 264 32 232 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 740] 100 740 560 264 32 232 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 759] 103 759 1586 822 64 758 0 40960 0 0 avahi-daemon Dec 26 10:46:08 volumio kernel: [ 760] 101 760 1885 986 96 890 0 32768 0 -900 dbus-daemon Dec 26 10:46:08 volumio kernel: [ 768] 0 768 9794 1771 174 1597 0 69632 0 0 systemd-logind Dec 26 10:46:08 volumio kernel: [ 772] 65534 772 1250 601 32 569 0 36864 0 0 thd Dec 26 10:46:08 volumio kernel: [ 784] 1000 784 11914 2501 224 2277 0 86016 0 0 volumio-remote- Dec 26 10:46:08 volumio kernel: [ 793] 0 793 32080 9847 1193 8654 0 258048 0 0 node Dec 26 10:46:08 volumio kernel: [ 815] 103 815 1545 367 32 335 0 36864 0 0 avahi-daemon Dec 26 10:46:08 volumio kernel: [ 862] 0 862 2810 1368 64 1304 0 45056 0 0 bluetoothd Dec 26 10:46:08 volumio kernel: [ 960] 0 960 3274 1319 96 1223 0 49152 0 0 wpa_supplicant Dec 26 10:46:08 volumio kernel: [ 982] 100 982 603 494 64 430 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 983] 0 983 643 477 32 445 0 28672 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 985] 100 985 560 280 32 248 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 986] 100 986 560 267 32 235 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 1081] 0 1081 1409 694 32 662 0 32768 0 0 bash Dec 26 10:46:08 volumio kernel: [ 1083] 0 1083 930 582 32 550 0 28672 0 0 ip Dec 26 10:46:08 volumio kernel: [ 1085] 0 1085 1393 484 32 452 0 36864 0 0 sed Dec 26 10:46:08 volumio kernel: [ 1086] 0 1086 1409 446 33 413 0 32768 0 0 bash Dec 26 10:46:08 volumio kernel: [ 1102] 0 1102 11194 1871 128 1743 0 77824 0 0 bluealsa Dec 26 10:46:08 volumio kernel: [ 1107] 106 1107 4362 4031 2454 1577 0 65536 0 0 ntpd Dec 26 10:46:08 volumio kernel: [ 1129] 0 1129 520 342 0 342 0 32768 0 0 ifplugd Dec 26 10:46:08 volumio kernel: [ 1166] 0 1166 9839 1502 128 1374 0 69632 0 0 upowerd Dec 26 10:46:08 volumio kernel: [ 1177] 0 1177 16457 3202 416 2716 70 94208 0 0 nmbd Dec 26 10:46:08 volumio kernel: [ 1183] 0 1183 16383 1034 406 628 0 81920 0 0 nmbd Dec 26 10:46:08 volumio kernel: [ 1244] 100 1244 643 337 65 272 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 1245] 100 1245 564 310 33 277 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 1270] 1000 1270 70440 33662 21139 12523 0 999424 0 0 node Dec 26 10:46:08 volumio kernel: [ 1316] 100 1316 564 310 33 277 0 24576 0 0 dhcpcd Dec 26 10:46:08 volumio kernel: [ 1340] 0 1340 18061 3929 480 3404 45 114688 0 0 winbindd Dec 26 10:46:08 volumio kernel: [ 1353] 0 1353 18121 3681 486 3152 43 114688 0 0 winbindd Dec 26 10:46:08 volumio kernel: [ 1357] 0 1357 19290 4329 512 3623 194 118784 0 0 smbd Dec 26 10:46:08 volumio kernel: [ 1389] 0 1389 18897 2671 514 2157 0 110592 0 0 smbd-notifyd Dec 26 10:46:08 volumio kernel: [ 1390] 0 1390 18897 1297 514 783 0 110592 0 0 cleanupd Dec 26 10:46:08 volumio kernel: [ 1405] 0 1405 18061 2794 489 2305 0 110592 0 0 winbindd Dec 26 10:46:08 volumio kernel: [ 1422] 1000 1422 1400 647 32 615 0 36864 0 0 network_monitor Dec 26 10:46:08 volumio kernel: [ 1457] 1000 1457 31903 8450 923 7527 0 225280 0 0 node Dec 26 10:46:08 volumio kernel: [ 1467] 1000 1467 32991 11568 2253 9315 0 299008 0 0 node Dec 26 10:46:08 volumio kernel: [ 1468] 1000 1468 32994 11515 2247 9268 0 303104 0 0 node Dec 26 10:46:08 volumio kernel: [ 1470] 1000 1470 32924 11533 2266 9267 0 299008 0 0 node Dec 26 10:46:08 volumio kernel: [ 1550] 102 1550 120545 26247 7487 18760 0 688128 0 0 mpd Dec 26 10:46:08 volumio kernel: [ 1651] 1000 1651 36126 3209 384 2825 0 143360 0 0 upmpdcli Dec 26 10:46:08 volumio kernel: [ 1784] 104 1784 37564 3013 378 2635 0 155648 0 0 shairport-sync Dec 26 10:46:08 volumio kernel: [ 1854] 0 1854 1409 654 32 622 0 32768 0 0 mpd_monitor.sh Dec 26 10:46:08 volumio kernel: [ 2696] 0 2696 2293 1325 870 455 0 45056 0 0 mount.ntfs Dec 26 10:46:08 volumio kernel: [ 4471] 1000 4471 515 299 0 299 0 28672 0 0 startx Dec 26 10:46:08 volumio kernel: [ 4494] 1000 4494 1001 545 32 513 0 28672 0 0 xinit Dec 26 10:46:08 volumio kernel: [ 4495] 1000 4495 62215 19696 5408 13296 992 393216 0 0 Xorg Dec 26 10:46:08 volumio kernel: [ 4520] 1000 4520 1400 679 32 647 0 36864 0 0 volumiokiosk.sh Dec 26 10:46:08 volumio kernel: [ 4557] 1000 4557 1505 367 96 271 0 36864 0 0 ssh-agent Dec 26 10:46:08 volumio kernel: [ 4564] 1000 4564 24544 4427 736 3691 0 118784 0 0 openbox Dec 26 10:46:08 volumio kernel: [ 4565] 1000 4565 195714 48066 8926 38690 450 966656 0 0 chromium Dec 26 10:46:08 volumio kernel: [ 4586] 1000 4586 3825 813 64 749 0 36864 0 0 chrome_crashpad Dec 26 10:46:08 volumio kernel: [ 4588] 1000 4588 1772 753 64 689 0 32768 0 0 chrome_crashpad Dec 26 10:46:08 volumio kernel: [ 4592] 1000 4592 116692 20348 4128 16220 0 585728 0 0 chromium Dec 26 10:46:08 volumio kernel: [ 4593] 1000 4593 116691 20273 4096 16177 0 581632 0 0 chromium Dec 26 10:46:08 volumio kernel: [ 4613] 1000 4613 116696 5217 4117 1100 0 270336 0 0 chromium Dec 26 10:46:08 volumio kernel: [ 4636] 1000 4636 141904 17656 4966 11439 1251 552960 0 200 chromium Dec 26 10:46:08 volumio kernel: [ 4639] 1000 4639 130045 25549 4839 20636 74 704512 0 200 chromium Dec 26 10:46:08 volumio kernel: [ 4642] 1000 4642 132805 11548 4344 7204 0 462848 0 200 chromium Dec 26 10:46:08 volumio kernel: [ 4670] 1000 4670 136397 16207 4887 11320 0 602112 0 300 chromium Dec 26 10:46:08 volumio kernel: [ 4915] 0 4915 3390 1983 192 1791 0 53248 0 -1000 sshd Dec 26 10:46:08 volumio kernel: [ 5014] 0 5014 3668 2289 256 2033 0 57344 0 0 sshd Dec 26 10:46:08 volumio kernel: [ 5031] 1000 5031 4062 2308 288 2020 0 57344 0 100 systemd Dec 26 10:46:08 volumio kernel: [ 5032] 1000 5032 8968 1125 691 434 0 65536 0 100 (sd-pam) Dec 26 10:46:08 volumio kernel: [ 5049] 1000 5049 1929 815 32 783 0 49152 0 200 mpris-proxy Dec 26 10:46:08 volumio kernel: [ 5050] 1000 5050 1792 943 64 879 0 40960 0 200 dbus-daemon Dec 26 10:46:08 volumio kernel: [ 5060] 1000 5060 3733 1541 315 1226 0 57344 0 0 sshd Dec 26 10:46:08 volumio kernel: [ 5061] 1000 5061 550 420 0 420 0 28672 0 0 sftp-server Dec 26 10:46:08 volumio kernel: [ 5193] 0 5193 3668 2294 256 2038 0 53248 0 0 sshd Dec 26 10:46:08 volumio kernel: [ 5237] 1000 5237 3733 1529 307 1222 0 53248 0 0 sshd Dec 26 10:46:08 volumio kernel: [ 5238] 1000 5238 983 846 224 622 0 24576 0 0 bash Dec 26 10:46:08 volumio kernel: [ 5322] 1000 5322 783 581 32 549 0 28672 0 0 volumio Dec 26 10:46:08 volumio kernel: [ 5323] 1000 5323 38011 14680 5602 9078 0 593920 0 0 node Dec 26 10:46:08 volumio kernel: [ 5349] 1000 5349 647 444 126 318 0 36864 0 0 minizip Dec 26 10:46:08 volumio kernel: [ 5369] 1000 5369 1115 379 0 379 0 36864 0 0 sleep Dec 26 10:46:08 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=chromium,pid=4670,uid=1000 Dec 26 10:46:08 volumio kernel: Out of memory: Killed process 4670 (chromium) total-vm:545588kB, anon-rss:19548kB, file-rss:45280kB, shmem-rss:0kB, UID:1000 pgtables:588kB oom_score_adj:300 Dec 26 10:46:10 volumio volumio[1270]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 26 10:46:10 volumio volumio[1270]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Dec 26 10:46:10 volumio volumio[1270]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Dec 26 10:46:10 volumio volumio[1270]: info: Folder /tmp/plugins removed Dec 26 10:46:11 volumio volumio[1270]: info: Check plugin dependencies Dec 26 10:46:11 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 10:46:11 volumio volumio[1270]: info: Checking if plugin already exists Dec 26 10:46:11 volumio volumio[1270]: info: Rename folder Dec 26 10:46:11 volumio volumio[1270]: info: Folder /tmp/downloaded_plugin.zip removed Dec 26 10:46:11 volumio volumio[1270]: info: Move to category Dec 26 10:46:14 volumio volumio[1270]: info: Checking if install.sh is present Dec 26 10:46:14 volumio volumio[1270]: info: Executing install.sh Dec 26 10:46:14 volumio sudo[5401]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppy_screensaver/install.sh Dec 26 10:46:14 volumio sudo[5401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:46:14 volumio volumio[1270]: info: Installing peppy-screensaver Dependencies Dec 26 10:46:14 volumio volumio[1270]: info: ___Install peppyalsa ... Dec 26 10:46:14 volumio sudo[5424]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/peppyalsa.tar.gz -C /usr/local/lib Dec 26 10:46:14 volumio sudo[5424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:14 volumio sudo[5424]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:14 volumio volumio[1270]: info: ___Install peppyalsa client ... Dec 26 10:46:14 volumio volumio[1270]: info: ___commandline tool already compiled Dec 26 10:46:14 volumio volumio[1270]: info: ___Install PeppyMeter... Dec 26 10:46:24 volumio volumio[1270]: info: ___Install PeppySpectrum... Dec 26 10:46:28 volumio ntpd[1107]: PROTO: 185.157.229.254 unlink local addr 192.168.178.50 -> Dec 26 10:46:28 volumio volumio[1270]: info: ___Install python pygame... Dec 26 10:46:28 volumio sudo[5573]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pygame2.tar.gz -C /usr Dec 26 10:46:28 volumio sudo[5573]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:28 volumio sudo[5573]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio volumio[1270]: info: ___Install python socket-IO... Dec 26 10:46:29 volumio sudo[5580]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/socketio.tar.gz -C /usr/local/lib/python3.7/dist-packages Dec 26 10:46:29 volumio sudo[5580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:29 volumio sudo[5580]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio volumio[1270]: info: ___Install python pillow... Dec 26 10:46:29 volumio sudo[5587]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/PIL.tar.gz -C /usr/local/lib/python3.7/dist-packages Dec 26 10:46:29 volumio sudo[5587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:29 volumio sudo[5587]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio volumio[1270]: info: ___Install python screenshot... Dec 26 10:46:29 volumio sudo[5594]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pyscreenshot.tar.gz -C /usr/local/lib/python3.7/dist-packages Dec 26 10:46:29 volumio sudo[5594]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:29 volumio sudo[5594]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio sudo[5597]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -rd /data/plugins/user_interface/peppy_screensaver/dependencies Dec 26 10:46:29 volumio sudo[5597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:29 volumio sudo[5597]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio volumio[1270]: info: ___Modify PeppyMeter config for Volumio... Dec 26 10:46:29 volumio volumio[1270]: info: ___Modify PeppySpectrum config for Volumio... Dec 26 10:46:29 volumio sudo[5401]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:29 volumio volumio[1270]: info: ___Finished Dec 26 10:46:29 volumio volumio[1270]: Plugin install end detected on script Dec 26 10:46:29 volumio volumio[1270]: info: Install script completed Dec 26 10:46:29 volumio volumio[1270]: info: Adding reference to registry Dec 26 10:46:29 volumio volumio[1270]: info: Done installing plugin. Dec 26 10:46:29 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 10:46:29 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 26 10:46:29 volumio volumio[1270]: info: Folder /tmp/plugins removed Dec 26 10:46:29 volumio volumio[1270]: info: Folder /tmp/downloaded_plugin.zip removed Dec 26 10:46:29 volumio volumio[1270]: info: Folder /data/temp removed Dec 26 10:46:36 volumio sshd[5193]: pam_unix(sshd:session): session closed for user volumio Dec 26 10:46:36 volumio systemd[1]: session-5.scope: Deactivated successfully. Dec 26 10:46:36 volumio systemd[1]: session-5.scope: Consumed 12.607s CPU time. Dec 26 10:46:36 volumio systemd-logind[768]: Session 5 logged out. Waiting for processes to exit. Dec 26 10:46:36 volumio systemd-logind[768]: Removed session 5. Dec 26 10:46:47 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 10:46:47 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 26 10:46:55 volumio volumio[1270]: info: Enabling plugin peppy_screensaver Dec 26 10:46:55 volumio volumio[1270]: info: Loading plugin "peppy_screensaver"... Dec 26 10:46:55 volumio volumio[1270]: error: !!!! WARNING !!!! Dec 26 10:46:55 volumio volumio[1270]: error: The plugin user_interface/peppy_screensaver failed to load, setting it to stopped. Error: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry Dec 26 10:46:55 volumio volumio[1270]: error: Stack trace: Error: Cannot find module '/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/index'. Please verify that the package.json has a valid "main" entry Dec 26 10:46:55 volumio volumio[1270]: at tryPackage (node:internal/modules/cjs/loader:415:19) Dec 26 10:46:55 volumio volumio[1270]: at Module._findPath (node:internal/modules/cjs/loader:665:18) Dec 26 10:46:55 volumio volumio[1270]: at Module._resolveFilename (node:internal/modules/cjs/loader:1034:27) Dec 26 10:46:55 volumio volumio[1270]: at Module._load (node:internal/modules/cjs/loader:901:27) Dec 26 10:46:55 volumio volumio[1270]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 26 10:46:55 volumio volumio[1270]: at require (node:internal/modules/helpers:130:18) Dec 26 10:46:55 volumio volumio[1270]: at Object. (/data/plugins/user_interface/peppy_screensaver/index.js:4:8) Dec 26 10:46:55 volumio volumio[1270]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 26 10:46:55 volumio volumio[1270]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 26 10:46:55 volumio volumio[1270]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 26 10:46:55 volumio volumio[1270]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 26 10:46:55 volumio volumio[1270]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 26 10:46:55 volumio volumio[1270]: at require (node:internal/modules/helpers:130:18) Dec 26 10:46:55 volumio volumio[1270]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Dec 26 10:46:55 volumio volumio[1270]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 26 10:46:55 volumio volumio[1270]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 26 10:46:55 volumio volumio[1270]: error: !!!! WARNING !!!! Dec 26 10:46:55 volumio volumio[1270]: info: Preparing to generate the ALSA configuration file Dec 26 10:46:55 volumio volumio[1270]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 26 10:46:55 volumio volumio[1270]: info: Reading ALSA contributions from plugins. Dec 26 10:46:55 volumio volumio[1270]: info: Asound.conf file written Dec 26 10:46:55 volumio sudo[5662]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 10:46:55 volumio sudo[5662]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:46:55 volumio sudo[5662]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:55 volumio volumio[1270]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 10:46:55 volumio volumio[1270]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 10:46:55 volumio volumio[1270]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 26 10:46:55 volumio volumio[1270]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Dec 26 10:46:55 volumio volumio[1270]: info: Output device has changed, restarting MPD Dec 26 10:46:55 volumio volumio[1270]: info: Output device has changed, restarting Shairport Sync Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:46:55 volumio sudo[5668]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 10:46:55 volumio sudo[5668]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:46:55 volumio sudo[5668]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:55 volumio sudo[5671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 10:46:55 volumio sudo[5671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:46:55 volumio volumio[1270]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 10:46:55 volumio volumio[1270]: info: Done. Dec 26 10:46:55 volumio volumio[1270]: info: MPD Permissions set Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 10:46:55 volumio volumio[1270]: info: Starting Shairport Sync Dec 26 10:46:55 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 10:46:55 volumio sudo[5685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 10:46:55 volumio sudo[5685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 10:46:55 volumio upmpdcli[1651]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 26 10:46:55 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 10:46:55 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 10:46:55 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:46:55 volumio systemd[1]: shairport-sync.service: Consumed 5.701s CPU time. Dec 26 10:46:55 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 10:46:55 volumio sudo[5685]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:55 volumio volumio[1270]: info: Shairport-Sync Started Dec 26 10:46:55 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 10:46:55 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 10:46:55 volumio systemd[1]: mpd.service: Consumed 1min 9.818s CPU time. Dec 26 10:46:55 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 10:46:55 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 10:46:55 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 10:46:55 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 10:46:55 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 10:46:55 volumio sudo[5690]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 10:46:55 volumio sudo[5690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 10:46:55 volumio sudo[5690]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:57 volumio mpd[5706]: 2025-12-26T10:46:57 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 10:46:57 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 10:46:57 volumio sudo[5671]: pam_unix(sudo:session): session closed for user root Dec 26 10:46:57 volumio volumio[1270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:46:57 volumio volumio[1270]: TypeError: Cannot read properties of null (reading 'printToastMessage') Dec 26 10:46:57 volumio volumio[1270]: at /volumio/app/index.js:1214:32 Dec 26 10:46:57 volumio volumio[1270]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 26 10:46:57 volumio volumio[1270]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 26 10:46:57 volumio volumio[1270]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Dec 26 10:46:57 volumio volumio[1270]: at /volumio/app/plugins/music_service/mpd/index.js:798:30 Dec 26 10:46:57 volumio volumio[1270]: at /volumio/app/plugins/music_service/mpd/index.js:945:9 Dec 26 10:46:57 volumio volumio[1270]: at ChildProcess.exithandler (node:child_process:413:7) Dec 26 10:46:57 volumio volumio[1270]: at ChildProcess.emit (node:events:514:28) Dec 26 10:46:57 volumio volumio[1270]: at maybeClose (node:internal/child_process:1105:16) Dec 26 10:46:57 volumio volumio[1270]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 26 10:46:57 volumio volumio[1270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 10:46:57 volumio upmpdcli[1651]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 26 10:46:58 volumio sudo[5737]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 10:45' Dec 26 10:46:58 volumio sudo[5737]: 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"