Dec 26 11:37:01 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Dec 26 11:37:01 volumio systemd[3800]: Activating special unit exit.target... Dec 26 11:37:01 volumio systemd[3800]: Stopped target default.target - Main User Target. Dec 26 11:37:01 volumio systemd[3800]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Dec 26 11:37:01 volumio mpris-proxy[3816]: org.bluez appeared Dec 26 11:37:01 volumio mpris-proxy[3816]: Bluetooth Adapter /org/bluez/hci0 found Dec 26 11:37:01 volumio mpris-proxy[3816]: Bluetooth Adapter /org/bluez/hci0 removed Dec 26 11:37:01 volumio mpris-proxy[3816]: org.bluez disappeared Dec 26 11:37:01 volumio systemd[3800]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Dec 26 11:37:01 volumio systemd[3800]: Stopping dbus.service - D-Bus User Message Bus... Dec 26 11:37:01 volumio systemd[3800]: Stopped dbus.service - D-Bus User Message Bus. Dec 26 11:37:01 volumio systemd[3800]: Removed slice session.slice - User Core Session Slice. Dec 26 11:37:01 volumio systemd[3800]: Stopped target basic.target - Basic System. Dec 26 11:37:01 volumio systemd[3800]: Stopped target paths.target - Paths. Dec 26 11:37:01 volumio systemd[3800]: Stopped target sockets.target - Sockets. Dec 26 11:37:01 volumio systemd[3800]: Stopped target timers.target - Timers. Dec 26 11:37:01 volumio systemd[3800]: Closed dbus.socket - D-Bus User Message Bus Socket. Dec 26 11:37:01 volumio systemd[3800]: Closed dirmngr.socket - GnuPG network certificate management daemon. Dec 26 11:37:01 volumio systemd[3800]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 26 11:37:01 volumio systemd[3800]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 26 11:37:01 volumio systemd[3800]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 26 11:37:01 volumio systemd[3800]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 26 11:37:01 volumio systemd[3800]: Removed slice app.slice - User Application Slice. Dec 26 11:37:01 volumio systemd[3800]: Reached target shutdown.target - Shutdown. Dec 26 11:37:01 volumio systemd[3800]: Finished systemd-exit.service - Exit the Session. Dec 26 11:37:01 volumio systemd[3800]: Reached target exit.target - Exit the Session. Dec 26 11:37:01 volumio systemd[1]: user@1000.service: Deactivated successfully. Dec 26 11:37:01 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Dec 26 11:37:02 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 26 11:37:02 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Dec 26 11:37:02 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Dec 26 11:37:02 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 26 11:37:02 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Dec 26 11:37:02 volumio systemd[1]: user-1000.slice: Consumed 9.075s CPU time. Dec 26 11:37:18 volumio sshd[3955]: Accepted password for volumio from 192.168.178.58 port 61645 ssh2 Dec 26 11:37:18 volumio sshd[3955]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0) Dec 26 11:37:18 volumio systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Dec 26 11:37:18 volumio systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 26 11:37:18 volumio systemd-logind[773]: New session 5 of user volumio. Dec 26 11:37:18 volumio systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 26 11:37:18 volumio systemd[1]: Starting user@1000.service - User Manager for UID 1000... Dec 26 11:37:18 volumio (systemd)[3973]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Dec 26 11:37:19 volumio systemd[3973]: Queued start job for default target default.target. Dec 26 11:37:19 volumio systemd[3973]: Created slice app.slice - User Application Slice. Dec 26 11:37:19 volumio systemd[3973]: Reached target paths.target - Paths. Dec 26 11:37:19 volumio systemd[3973]: Reached target timers.target - Timers. Dec 26 11:37:19 volumio systemd[3973]: Starting dbus.socket - D-Bus User Message Bus Socket... Dec 26 11:37:19 volumio systemd[3973]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Dec 26 11:37:19 volumio systemd[3973]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 26 11:37:19 volumio systemd[3973]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 26 11:37:19 volumio systemd[3973]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 26 11:37:19 volumio systemd[3973]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 26 11:37:19 volumio systemd[3973]: Listening on dbus.socket - D-Bus User Message Bus Socket. Dec 26 11:37:19 volumio systemd[3973]: Reached target sockets.target - Sockets. Dec 26 11:37:19 volumio systemd[3973]: Reached target basic.target - Basic System. Dec 26 11:37:19 volumio systemd[1]: Started user@1000.service - User Manager for UID 1000. Dec 26 11:37:19 volumio systemd[3973]: Started mpris-proxy.service - Bluetooth mpris proxy. Dec 26 11:37:19 volumio systemd[1]: Started session-5.scope - Session 5 of User volumio. Dec 26 11:37:19 volumio systemd[3973]: Reached target default.target - Main User Target. Dec 26 11:37:19 volumio systemd[3973]: Startup finished in 645ms. Dec 26 11:37:19 volumio systemd[3973]: Created slice session.slice - User Core Session Slice. Dec 26 11:37:19 volumio systemd[3973]: Starting dbus.service - D-Bus User Message Bus... Dec 26 11:37:19 volumio systemd[3973]: Started dbus.service - D-Bus User Message Bus. Dec 26 11:37:19 volumio sshd[3955]: pam_env(sshd:session): deprecated reading of user environment enabled Dec 26 11:37:57 volumio kernel: minizip invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Dec 26 11:37:57 volumio kernel: CPU: 3 UID: 1000 PID: 4082 Comm: minizip Tainted: G WC 6.12.47-v7l+ #1904 Dec 26 11:37:57 volumio kernel: Tainted: [W]=WARN, [C]=CRAP Dec 26 11:37:57 volumio kernel: Hardware name: BCM2711 Dec 26 11:37:57 volumio kernel: Call trace: Dec 26 11:37:57 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Dec 26 11:37:57 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Dec 26 11:37:57 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Dec 26 11:37:57 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Dec 26 11:37:57 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Dec 26 11:37:57 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa60/0x1008 Dec 26 11:37:57 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Dec 26 11:37:57 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Dec 26 11:37:57 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Dec 26 11:37:57 volumio kernel: kmem_cache_alloc_lru_noprof from ovl_alloc_inode+0x24/0x70 [overlay] Dec 26 11:37:57 volumio kernel: ovl_alloc_inode [overlay] from alloc_inode+0x24/0xdc Dec 26 11:37:57 volumio kernel: alloc_inode from iget5_locked+0x44/0x7c Dec 26 11:37:57 volumio kernel: iget5_locked from ovl_get_inode+0x350/0x5bc [overlay] Dec 26 11:37:57 volumio kernel: ovl_get_inode [overlay] from ovl_lookup+0x3ac/0xc8c [overlay] Dec 26 11:37:57 volumio kernel: ovl_lookup [overlay] from __lookup_slow+0x90/0x13c Dec 26 11:37:57 volumio kernel: __lookup_slow from walk_component+0xf4/0x164 Dec 26 11:37:57 volumio kernel: walk_component from path_lookupat+0x74/0x1a4 Dec 26 11:37:57 volumio kernel: path_lookupat from filename_lookup+0xac/0x180 Dec 26 11:37:57 volumio kernel: filename_lookup from vfs_statx+0x70/0xe4 Dec 26 11:37:57 volumio kernel: vfs_statx from do_statx+0x48/0x8c Dec 26 11:37:57 volumio kernel: do_statx from sys_statx+0x78/0xf0 Dec 26 11:37:57 volumio kernel: sys_statx from ret_fast_syscall+0x0/0x5c Dec 26 11:37:57 volumio kernel: Exception stack(0xf10a9fa8 to 0xf10a9ff0) Dec 26 11:37:57 volumio kernel: 9fa0: beca6c00 beca6d60 ffffff9c beca6e10 00000800 000007ff Dec 26 11:37:57 volumio kernel: 9fc0: beca6c00 beca6d60 00012f04 0000018d 00010dcc b6f62d80 00000000 beca6f84 Dec 26 11:37:57 volumio kernel: 9fe0: 00000000 beca6b78 ffffff9c b6e722e0 Dec 26 11:37:57 volumio kernel: Mem-Info: Dec 26 11:37:57 volumio kernel: active_anon:1458 inactive_anon:83398 isolated_anon:0 active_file:63337 inactive_file:118738 isolated_file:32 unevictable:5803 dirty:6072 writeback:0 slab_reclaimable:10602 slab_unreclaimable:6689 mapped:75574 shmem:5636 pagetables:2998 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:1706377 free_pcp:0 free_cma:126521 Dec 26 11:37:57 volumio kernel: Node 0 active_anon:5832kB inactive_anon:333592kB active_file:253348kB inactive_file:474952kB unevictable:23212kB isolated(anon):0kB isolated(file):128kB mapped:302296kB dirty:24288kB writeback:0kB shmem:22544kB writeback_tmp:0kB kernel_stack:3200kB pagetables:11992kB sec_pagetables:0kB all_unreclaimable? no Dec 26 11:37:57 volumio kernel: DMA free:532120kB boost:36864kB min:53248kB low:57344kB high:61440kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:908kB inactive_file:1640kB unevictable:0kB writepending:172kB present:786432kB managed:626192kB mlocked:0kB bounce:0kB free_pcp:0kB local_pcp:0kB free_cma:506084kB Dec 26 11:37:57 volumio kernel: lowmem_reserve[]: 0 0 7232 7232 Dec 26 11:37:57 volumio kernel: DMA: 629*4kB (UMEC) 312*8kB (UEC) 159*16kB (UEC) 63*32kB (UMEC) 48*64kB (UME) 32*128kB (UMC) 18*256kB (UMC) 10*512kB (UMC) 6*1024kB (UMC) 0*2048kB 122*4096kB (C) = 532324kB Dec 26 11:37:57 volumio kernel: 189289 total pagecache pages Dec 26 11:37:57 volumio kernel: 0 pages in swap cache Dec 26 11:37:57 volumio kernel: Free swap = 0kB Dec 26 11:37:57 volumio kernel: Total swap = 0kB Dec 26 11:37:57 volumio kernel: 2048000 pages RAM Dec 26 11:37:57 volumio kernel: 1851392 pages HighMem/MovableOnly Dec 26 11:37:57 volumio kernel: 40060 pages reserved Dec 26 11:37:57 volumio kernel: 131072 pages cma reserved Dec 26 11:37:57 volumio kernel: Tasks state (memory values in pages): Dec 26 11:37:57 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Dec 26 11:37:57 volumio kernel: [ 466] 0 466 4675 2001 192 1489 320 57344 0 -250 systemd-journal Dec 26 11:37:57 volumio kernel: [ 488] 0 488 6006 1251 256 995 0 40960 0 -1000 systemd-udevd Dec 26 11:37:57 volumio kernel: [ 732] 100 732 603 502 64 438 0 32768 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 734] 0 734 564 390 32 358 0 32768 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 735] 100 735 560 258 32 226 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 736] 100 736 560 226 32 194 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 739] 0 739 1959 1147 765 382 0 40960 0 0 haveged Dec 26 11:37:57 volumio kernel: [ 740] 108 740 1731 787 64 723 0 40960 0 0 rpcbind Dec 26 11:37:57 volumio kernel: [ 757] 103 757 1586 839 64 775 0 40960 0 0 avahi-daemon Dec 26 11:37:57 volumio kernel: [ 758] 101 758 1882 977 128 849 0 36864 0 -900 dbus-daemon Dec 26 11:37:57 volumio kernel: [ 773] 0 773 7745 1752 170 1582 0 61440 0 0 systemd-logind Dec 26 11:37:57 volumio kernel: [ 775] 65534 775 1250 562 32 530 0 36864 0 0 thd Dec 26 11:37:57 volumio kernel: [ 782] 1000 782 11914 2522 224 2298 0 90112 0 0 volumio-remote- Dec 26 11:37:57 volumio kernel: [ 787] 0 787 32098 9898 1225 8673 0 258048 0 0 node Dec 26 11:37:57 volumio kernel: [ 808] 103 808 1545 335 32 303 0 36864 0 0 avahi-daemon Dec 26 11:37:57 volumio kernel: [ 902] 0 902 3274 1343 96 1247 0 53248 0 0 wpa_supplicant Dec 26 11:37:57 volumio kernel: [ 952] 100 952 639 478 64 414 0 32768 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 953] 0 953 643 461 32 429 0 32768 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 954] 100 954 560 280 32 248 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 955] 100 955 560 266 32 234 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 1010] 0 1010 1409 696 32 664 0 36864 0 0 bash Dec 26 11:37:57 volumio kernel: [ 1011] 0 1011 930 582 32 550 0 28672 0 0 ip Dec 26 11:37:57 volumio kernel: [ 1012] 0 1012 1393 461 32 429 0 36864 0 0 sed Dec 26 11:37:57 volumio kernel: [ 1013] 0 1013 1409 446 33 413 0 28672 0 0 bash Dec 26 11:37:57 volumio kernel: [ 1050] 106 1050 4362 4012 2422 1590 0 57344 0 0 ntpd Dec 26 11:37:57 volumio kernel: [ 1058] 0 1058 2810 1414 96 1318 0 49152 0 0 bluetoothd Dec 26 11:37:57 volumio kernel: [ 1065] 0 1065 520 348 0 348 0 28672 0 0 ifplugd Dec 26 11:37:57 volumio kernel: [ 1138] 0 1138 11194 1818 96 1722 0 73728 0 0 bluealsa Dec 26 11:37:57 volumio kernel: [ 1181] 0 1181 9839 1467 96 1371 0 57344 0 0 upowerd Dec 26 11:37:57 volumio kernel: [ 1193] 0 1193 16457 3152 384 2698 70 98304 0 0 nmbd Dec 26 11:37:57 volumio kernel: [ 1202] 0 1202 16383 1032 406 626 0 90112 0 0 nmbd Dec 26 11:37:57 volumio kernel: [ 1203] 100 1203 643 314 66 248 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 1225] 100 1225 564 293 33 260 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 1304] 1000 1304 70162 33348 20853 12495 0 954368 0 0 node Dec 26 11:37:57 volumio kernel: [ 1335] 0 1335 18061 3956 480 3432 44 106496 0 0 winbindd Dec 26 11:37:57 volumio kernel: [ 1363] 100 1363 564 292 33 259 0 28672 0 0 dhcpcd Dec 26 11:37:57 volumio kernel: [ 1394] 0 1394 18121 3645 486 3116 43 106496 0 0 winbindd Dec 26 11:37:57 volumio kernel: [ 1398] 0 1398 19290 4287 512 3581 194 122880 0 0 smbd Dec 26 11:37:57 volumio kernel: [ 1410] 0 1410 18897 2642 509 2133 0 118784 0 0 smbd-notifyd Dec 26 11:37:57 volumio kernel: [ 1411] 0 1411 18897 1272 509 763 0 118784 0 0 cleanupd Dec 26 11:37:57 volumio kernel: [ 1420] 0 1420 18061 2762 489 2273 0 106496 0 0 winbindd Dec 26 11:37:57 volumio kernel: [ 1444] 1000 1444 1400 631 32 599 0 32768 0 0 network_monitor Dec 26 11:37:57 volumio kernel: [ 1486] 0 1486 2223 1321 869 452 0 40960 0 0 mount.ntfs Dec 26 11:37:57 volumio kernel: [ 1490] 1000 1490 31838 8444 909 7535 0 229376 0 0 node Dec 26 11:37:57 volumio kernel: [ 1500] 1000 1500 32909 11511 2168 9343 0 299008 0 0 node Dec 26 11:37:57 volumio kernel: [ 1501] 1000 1501 32979 11560 2246 9314 0 311296 0 0 node Dec 26 11:37:57 volumio kernel: [ 1502] 1000 1502 32981 11483 2197 9286 0 311296 0 0 node Dec 26 11:37:57 volumio kernel: [ 1592] 102 1592 115205 25462 6744 18718 0 671744 0 0 mpd Dec 26 11:37:57 volumio kernel: [ 1863] 104 1863 37564 3061 384 2677 0 143360 0 0 shairport-sync Dec 26 11:37:57 volumio kernel: [ 1896] 0 1896 1409 686 32 654 0 36864 0 0 mpd_monitor.sh Dec 26 11:37:57 volumio kernel: [ 2191] 1000 2191 36121 3215 384 2831 0 147456 0 0 upmpdcli Dec 26 11:37:57 volumio kernel: [ 2447] 1000 2447 515 319 0 319 0 24576 0 0 startx Dec 26 11:37:57 volumio kernel: [ 2470] 1000 2470 1001 564 32 532 0 32768 0 0 xinit Dec 26 11:37:57 volumio kernel: [ 2471] 1000 2471 62318 19318 5536 13310 472 385024 0 0 Xorg Dec 26 11:37:57 volumio kernel: [ 2479] 1000 2479 1400 662 32 630 0 36864 0 0 volumiokiosk.sh Dec 26 11:37:57 volumio kernel: [ 2516] 1000 2516 1505 381 96 285 0 32768 0 0 ssh-agent Dec 26 11:37:57 volumio kernel: [ 2523] 1000 2523 22477 4424 736 3688 0 114688 0 0 openbox Dec 26 11:37:57 volumio kernel: [ 2524] 1000 2524 187192 47319 8104 38781 434 946176 0 0 chromium Dec 26 11:37:57 volumio kernel: [ 2563] 1000 2563 3825 819 64 755 0 32768 0 0 chrome_crashpad Dec 26 11:37:57 volumio kernel: [ 2565] 1000 2565 1772 734 64 670 0 40960 0 0 chrome_crashpad Dec 26 11:37:57 volumio kernel: [ 2569] 1000 2569 116692 20194 4128 16066 0 598016 0 0 chromium Dec 26 11:37:57 volumio kernel: [ 2570] 1000 2570 116691 20186 4128 16058 0 598016 0 0 chromium Dec 26 11:37:57 volumio kernel: [ 2573] 1000 2573 116696 5216 4117 1099 0 290816 0 0 chromium Dec 26 11:37:57 volumio kernel: [ 2599] 1000 2599 134854 11684 4398 7254 32 471040 0 200 chromium Dec 26 11:37:57 volumio kernel: [ 2998] 1000 2998 132431 10817 4249 6568 0 434176 0 200 chromium Dec 26 11:37:57 volumio kernel: [ 2999] 1000 2999 117924 5068 4198 870 0 282624 0 200 chromium Dec 26 11:37:57 volumio kernel: [ 3487] 1000 3487 130516 24112 4352 19760 0 667648 0 200 chromium Dec 26 11:37:57 volumio kernel: [ 3488] 1000 3488 139820 16793 4917 11151 725 544768 0 200 chromium Dec 26 11:37:57 volumio kernel: [ 3651] 0 3651 3390 1983 192 1791 0 49152 0 -1000 sshd Dec 26 11:37:57 volumio kernel: [ 3955] 0 3955 3668 2286 256 2030 0 57344 0 0 sshd Dec 26 11:37:57 volumio kernel: [ 3973] 1000 3973 4062 2290 288 2002 0 57344 0 100 systemd Dec 26 11:37:57 volumio kernel: [ 3974] 1000 3974 9016 1137 707 430 0 61440 0 100 (sd-pam) Dec 26 11:37:57 volumio kernel: [ 3989] 1000 3989 1929 828 32 796 0 36864 0 200 mpris-proxy Dec 26 11:37:57 volumio kernel: [ 3990] 1000 3990 1792 914 64 850 0 36864 0 200 dbus-daemon Dec 26 11:37:57 volumio kernel: [ 4000] 1000 4000 3733 1538 311 1227 0 57344 0 0 sshd Dec 26 11:37:57 volumio kernel: [ 4001] 1000 4001 983 830 224 606 0 32768 0 0 bash Dec 26 11:37:57 volumio kernel: [ 4053] 0 4053 1115 361 0 361 0 32768 0 0 sleep Dec 26 11:37:57 volumio kernel: [ 4054] 1000 4054 783 610 32 578 0 24576 0 0 volumio Dec 26 11:37:57 volumio kernel: [ 4055] 1000 4055 38018 14634 5643 8991 0 589824 0 0 node Dec 26 11:37:57 volumio kernel: [ 4079] 1000 4079 1115 375 0 375 0 36864 0 0 sleep Dec 26 11:37:57 volumio kernel: [ 4082] 1000 4082 573 403 96 307 0 28672 0 0 minizip Dec 26 11:37:57 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=chromium,pid=3487,uid=1000 Dec 26 11:37:57 volumio kernel: Out of memory: Killed process 3487 (chromium) total-vm:522064kB, anon-rss:17408kB, file-rss:79040kB, shmem-rss:0kB, UID:1000 pgtables:652kB oom_score_adj:200 Dec 26 11:38:08 volumio volumio[1304]: 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 11:38:08 volumio volumio[1304]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Dec 26 11:38:08 volumio volumio[1304]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/peppy_screensaver.zip Dec 26 11:38:08 volumio volumio[1304]: info: Folder /tmp/plugins removed Dec 26 11:38:11 volumio volumio[1304]: info: Check plugin dependencies Dec 26 11:38:12 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 11:38:12 volumio volumio[1304]: info: Checking if plugin already exists Dec 26 11:38:12 volumio volumio[1304]: info: Rename folder Dec 26 11:38:12 volumio volumio[1304]: info: Folder /tmp/downloaded_plugin.zip removed Dec 26 11:38:12 volumio volumio[1304]: info: Move to category Dec 26 11:38:13 volumio volumio[1304]: info: Checking if install.sh is present Dec 26 11:38:13 volumio volumio[1304]: info: Executing install.sh Dec 26 11:38:13 volumio sudo[4155]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/peppy_screensaver/install.sh Dec 26 11:38:13 volumio sudo[4155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 11:38:13 volumio volumio[1304]: info: Installing peppy-screensaver Dependencies Dec 26 11:38:13 volumio volumio[1304]: info: ___Install peppyalsa ... Dec 26 11:38:13 volumio sudo[4178]: 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 11:38:13 volumio sudo[4178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:13 volumio sudo[4178]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:13 volumio volumio[1304]: info: ___Install peppyalsa client ... Dec 26 11:38:13 volumio volumio[1304]: info: ___commandline tool already compiled Dec 26 11:38:13 volumio volumio[1304]: info: ___Install PeppyMeter... Dec 26 11:38:24 volumio volumio[1304]: info: ___Install PeppySpectrum... Dec 26 11:38:26 volumio volumio[1304]: info: ___Install python pygame... Dec 26 11:38:26 volumio sudo[4325]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/tar -xzf /data/plugins/user_interface/peppy_screensaver/dependencies/pygame2.tar.gz -C /usr Dec 26 11:38:26 volumio sudo[4325]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:28 volumio sudo[4325]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:28 volumio volumio[1304]: info: ___Install python socket-IO... Dec 26 11:38:28 volumio sudo[4335]: 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 11:38:28 volumio sudo[4335]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:29 volumio sudo[4335]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:29 volumio volumio[1304]: info: ___Install python pillow... Dec 26 11:38:29 volumio sudo[4342]: 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 11:38:29 volumio sudo[4342]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:30 volumio sudo[4342]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:30 volumio volumio[1304]: info: ___Install python screenshot... Dec 26 11:38:30 volumio sudo[4349]: 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 11:38:30 volumio sudo[4349]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:31 volumio sudo[4349]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:31 volumio sudo[4352]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/rm -rd /data/plugins/user_interface/peppy_screensaver/dependencies Dec 26 11:38:31 volumio sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:31 volumio sudo[4352]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:31 volumio volumio[1304]: info: ___Modify PeppyMeter config for Volumio... Dec 26 11:38:31 volumio volumio[1304]: info: ___Modify PeppySpectrum config for Volumio... Dec 26 11:38:31 volumio sudo[4155]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:31 volumio volumio[1304]: info: ___Finished Dec 26 11:38:31 volumio volumio[1304]: Plugin install end detected on script Dec 26 11:38:31 volumio volumio[1304]: info: Install script completed Dec 26 11:38:31 volumio volumio[1304]: info: Adding reference to registry Dec 26 11:38:31 volumio volumio[1304]: info: Done installing plugin. Dec 26 11:38:31 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 11:38:31 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 26 11:38:31 volumio volumio[1304]: info: Folder /tmp/plugins removed Dec 26 11:38:31 volumio volumio[1304]: info: Folder /tmp/downloaded_plugin.zip removed Dec 26 11:38:31 volumio volumio[1304]: info: Folder /data/temp removed Dec 26 11:38:40 volumio sshd[3955]: pam_unix(sshd:session): session closed for user volumio Dec 26 11:38:40 volumio systemd[1]: session-5.scope: Deactivated successfully. Dec 26 11:38:40 volumio systemd[1]: session-5.scope: Consumed 13.840s CPU time. Dec 26 11:38:40 volumio systemd-logind[773]: Session 5 logged out. Waiting for processes to exit. Dec 26 11:38:40 volumio systemd-logind[773]: Removed session 5. Dec 26 11:38:48 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 26 11:38:48 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 26 11:38:50 volumio systemd[1]: Stopping user@1000.service - User Manager for UID 1000... Dec 26 11:38:50 volumio systemd[3973]: Activating special unit exit.target... Dec 26 11:38:50 volumio systemd[3973]: Stopped target default.target - Main User Target. Dec 26 11:38:50 volumio systemd[3973]: Stopping mpris-proxy.service - Bluetooth mpris proxy... Dec 26 11:38:50 volumio mpris-proxy[3989]: org.bluez appeared Dec 26 11:38:50 volumio mpris-proxy[3989]: Bluetooth Adapter /org/bluez/hci0 found Dec 26 11:38:50 volumio mpris-proxy[3989]: Bluetooth Adapter /org/bluez/hci0 removed Dec 26 11:38:50 volumio mpris-proxy[3989]: org.bluez disappeared Dec 26 11:38:50 volumio systemd[3973]: Stopped mpris-proxy.service - Bluetooth mpris proxy. Dec 26 11:38:50 volumio systemd[3973]: Stopping dbus.service - D-Bus User Message Bus... Dec 26 11:38:50 volumio systemd[3973]: Stopped dbus.service - D-Bus User Message Bus. Dec 26 11:38:50 volumio systemd[3973]: Removed slice session.slice - User Core Session Slice. Dec 26 11:38:50 volumio systemd[3973]: Stopped target basic.target - Basic System. Dec 26 11:38:50 volumio systemd[3973]: Stopped target paths.target - Paths. Dec 26 11:38:50 volumio systemd[3973]: Stopped target sockets.target - Sockets. Dec 26 11:38:50 volumio systemd[3973]: Stopped target timers.target - Timers. Dec 26 11:38:50 volumio systemd[3973]: Closed dbus.socket - D-Bus User Message Bus Socket. Dec 26 11:38:50 volumio systemd[3973]: Closed dirmngr.socket - GnuPG network certificate management daemon. Dec 26 11:38:50 volumio systemd[3973]: Closed gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Dec 26 11:38:50 volumio systemd[3973]: Closed gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Dec 26 11:38:50 volumio systemd[3973]: Closed gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Dec 26 11:38:50 volumio systemd[3973]: Closed gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Dec 26 11:38:50 volumio systemd[3973]: Removed slice app.slice - User Application Slice. Dec 26 11:38:50 volumio systemd[3973]: Reached target shutdown.target - Shutdown. Dec 26 11:38:50 volumio systemd[3973]: Finished systemd-exit.service - Exit the Session. Dec 26 11:38:50 volumio systemd[3973]: Reached target exit.target - Exit the Session. Dec 26 11:38:50 volumio systemd[1]: user@1000.service: Deactivated successfully. Dec 26 11:38:50 volumio systemd[1]: Stopped user@1000.service - User Manager for UID 1000. Dec 26 11:38:50 volumio systemd[1]: Stopping user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Dec 26 11:38:50 volumio systemd[1]: run-user-1000.mount: Deactivated successfully. Dec 26 11:38:50 volumio systemd[1]: user-runtime-dir@1000.service: Deactivated successfully. Dec 26 11:38:50 volumio systemd[1]: Stopped user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Dec 26 11:38:50 volumio systemd[1]: Removed slice user-1000.slice - User Slice of UID 1000. Dec 26 11:38:50 volumio systemd[1]: user-1000.slice: Consumed 14.567s CPU time. Dec 26 11:38:51 volumio volumio[1304]: info: Enabling plugin peppy_screensaver Dec 26 11:38:51 volumio volumio[1304]: info: Loading plugin "peppy_screensaver"... Dec 26 11:38:51 volumio volumio[1304]: error: !!!! WARNING !!!! Dec 26 11:38:51 volumio volumio[1304]: 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 11:38:51 volumio volumio[1304]: 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 11:38:51 volumio volumio[1304]: at tryPackage (node:internal/modules/cjs/loader:415:19) Dec 26 11:38:51 volumio volumio[1304]: at Module._findPath (node:internal/modules/cjs/loader:665:18) Dec 26 11:38:51 volumio volumio[1304]: at Module._resolveFilename (node:internal/modules/cjs/loader:1034:27) Dec 26 11:38:51 volumio volumio[1304]: at Module._load (node:internal/modules/cjs/loader:901:27) Dec 26 11:38:51 volumio volumio[1304]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 26 11:38:51 volumio volumio[1304]: at require (node:internal/modules/helpers:130:18) Dec 26 11:38:51 volumio volumio[1304]: at Object. (/data/plugins/user_interface/peppy_screensaver/index.js:4:8) Dec 26 11:38:51 volumio volumio[1304]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Dec 26 11:38:51 volumio volumio[1304]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Dec 26 11:38:51 volumio volumio[1304]: at Module.load (node:internal/modules/cjs/loader:1091:32) Dec 26 11:38:51 volumio volumio[1304]: at Module._load (node:internal/modules/cjs/loader:938:12) Dec 26 11:38:51 volumio volumio[1304]: at Module.require (node:internal/modules/cjs/loader:1115:19) Dec 26 11:38:51 volumio volumio[1304]: at require (node:internal/modules/helpers:130:18) Dec 26 11:38:51 volumio volumio[1304]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Dec 26 11:38:51 volumio volumio[1304]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 26 11:38:51 volumio volumio[1304]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 26 11:38:51 volumio volumio[1304]: error: !!!! WARNING !!!! Dec 26 11:38:51 volumio volumio[1304]: info: Preparing to generate the ALSA configuration file Dec 26 11:38:51 volumio volumio[1304]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Dec 26 11:38:51 volumio volumio[1304]: info: Reading ALSA contributions from plugins. Dec 26 11:38:51 volumio volumio[1304]: info: Asound.conf file written Dec 26 11:38:52 volumio sudo[4419]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 26 11:38:52 volumio sudo[4419]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 11:38:52 volumio sudo[4419]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:52 volumio volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 26 11:38:52 volumio volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 26 11:38:52 volumio volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 26 11:38:52 volumio volumio[1304]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Dec 26 11:38:52 volumio volumio[1304]: info: Output device has changed, restarting MPD Dec 26 11:38:52 volumio volumio[1304]: info: Output device has changed, restarting Shairport Sync Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 11:38:52 volumio sudo[4425]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 11:38:52 volumio sudo[4425]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 11:38:52 volumio sudo[4427]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 11:38:52 volumio sudo[4427]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 11:38:52 volumio sudo[4425]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:52 volumio volumio[1304]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 11:38:52 volumio volumio[1304]: info: Done. Dec 26 11:38:52 volumio volumio[1304]: info: MPD Permissions set Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 11:38:52 volumio volumio[1304]: info: Starting Shairport Sync Dec 26 11:38:52 volumio sudo[4443]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 11:38:52 volumio sudo[4443]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 26 11:38:52 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 26 11:38:52 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 26 11:38:52 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 11:38:52 volumio systemd[1]: shairport-sync.service: Consumed 4.921s CPU time. Dec 26 11:38:52 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 26 11:38:52 volumio sudo[4443]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:52 volumio volumio[1304]: info: Shairport-Sync Started Dec 26 11:38:52 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 26 11:38:52 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 26 11:38:52 volumio systemd[1]: mpd.service: Consumed 1min 2.071s CPU time. Dec 26 11:38:52 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 26 11:38:52 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 26 11:38:52 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 26 11:38:52 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 26 11:38:52 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 26 11:38:52 volumio sudo[4447]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 11:38:52 volumio sudo[4447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 26 11:38:52 volumio sudo[4447]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:53 volumio mpd[4477]: 2025-12-26T11:38:53 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 11:38:54 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 26 11:38:54 volumio sudo[4427]: pam_unix(sudo:session): session closed for user root Dec 26 11:38:54 volumio volumio[1304]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 11:38:54 volumio volumio[1304]: TypeError: Cannot read properties of null (reading 'printToastMessage') Dec 26 11:38:54 volumio volumio[1304]: at /volumio/app/index.js:1214:32 Dec 26 11:38:54 volumio volumio[1304]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Dec 26 11:38:54 volumio volumio[1304]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Dec 26 11:38:54 volumio volumio[1304]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Dec 26 11:38:54 volumio volumio[1304]: at /volumio/app/plugins/music_service/mpd/index.js:798:30 Dec 26 11:38:54 volumio volumio[1304]: at /volumio/app/plugins/music_service/mpd/index.js:945:9 Dec 26 11:38:54 volumio volumio[1304]: at ChildProcess.exithandler (node:child_process:413:7) Dec 26 11:38:54 volumio volumio[1304]: at ChildProcess.emit (node:events:514:28) Dec 26 11:38:54 volumio volumio[1304]: at maybeClose (node:internal/child_process:1105:16) Dec 26 11:38:54 volumio volumio[1304]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 26 11:38:54 volumio volumio[1304]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 11:38:54 volumio sudo[4495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-26 11:37' Dec 26 11:38:54 volumio sudo[4495]: 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"