Aug 27 14:50:01 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 126. Aug 27 14:50:01 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:01 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:01 volumio upmpdcli[4659]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:50:01 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:50:01 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:50:16 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 127. Aug 27 14:50:16 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:16 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:16 volumio upmpdcli[4674]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:50:16 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:50:16 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:50:31 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:50:31 volumio kernel: CPU: 3 UID: 1000 PID: 4443 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:50:31 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:50:31 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:50:31 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:50:31 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:50:31 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:50:31 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:50:31 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:50:31 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:50:31 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:50:31 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:50:31 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:50:31 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:50:31 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:50:31 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:50:31 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:50:31 volumio kernel: Exception stack(0xf0d8dfa8 to 0xf0d8dff0) Aug 27 14:50:31 volumio kernel: dfa0: bed1fb98 00000001 00000003 004e4ac0 00008000 00000001 Aug 27 14:50:31 volumio kernel: dfc0: bed1fb98 00000001 bed1fb98 00000004 0001e6d8 b6f6ad80 00000000 bed1fabc Aug 27 14:50:31 volumio kernel: dfe0: 00042f18 bed1fa38 00016f98 b6ea37ac Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:40967 isolated_anon:0 active_file:13351 inactive_file:207567 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:17447 slab_unreclaimable:5922 mapped:38285 shmem:1939 pagetables:1475 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:670114 free_pcp:69 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:163868kB active_file:53404kB inactive_file:830268kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:153140kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2448kB pagetables:5900kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:551144kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:276kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 952*4kB (UMEC) 585*8kB (UMEC) 337*16kB (UMEC) 223*32kB (UMEC) 134*64kB (UMEC) 54*128kB (UEC) 33*256kB (UMEC) 12*512kB (UMEC) 5*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 551832kB Aug 27 14:50:31 volumio kernel: 224493 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2096 160 1520 416 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2515 224 2291 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 8545 1208 7337 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 1049 128 921 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1541 96 1445 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2860 448 2412 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1182] 1000 1182 62195 23885 12826 11059 0 839680 0 0 node Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4388 512 3682 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 7116 913 6203 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 10077 2193 7884 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1394] 1000 1394 32982 10193 2232 7961 0 311296 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 10150 2253 7897 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 1471] 102 1471 119475 23715 6649 17066 0 655360 0 0 mpd Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4281] 1000 4281 4031 2229 256 1973 0 57344 0 100 systemd Aug 27 14:50:31 volumio kernel: [ 4282] 1000 4282 9165 1085 600 485 0 69632 0 100 (sd-pam) Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=systemd,pid=4281,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 4281 (systemd) total-vm:16124kB, anon-rss:1024kB, file-rss:7892kB, shmem-rss:0kB, UID:1000 pgtables:56kB oom_score_adj:100 Aug 27 14:50:31 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:50:31 volumio kernel: CPU: 3 UID: 1000 PID: 4443 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:50:31 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:50:31 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:50:31 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:50:31 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:50:31 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:50:31 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:50:31 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:50:31 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:50:31 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:50:31 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:50:31 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:50:31 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:50:31 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:50:31 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:50:31 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:50:31 volumio kernel: Exception stack(0xf0d8dfa8 to 0xf0d8dff0) Aug 27 14:50:31 volumio kernel: dfa0: bed1fb98 00000001 00000003 004e4ac0 00008000 00000001 Aug 27 14:50:31 volumio kernel: dfc0: bed1fb98 00000001 bed1fb98 00000004 0001e6d8 b6f6ad80 00000000 bed1fabc Aug 27 14:50:31 volumio kernel: dfe0: 00042f18 bed1fa38 00016f98 b6ea37ac Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:40708 isolated_anon:0 active_file:13351 inactive_file:206649 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:17410 slab_unreclaimable:5922 mapped:38285 shmem:1939 pagetables:1475 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:671626 free_pcp:259 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:162832kB active_file:53404kB inactive_file:826596kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:153140kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2448kB pagetables:5900kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:551144kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:692kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 796*4kB (UMEC) 586*8kB (UMEC) 338*16kB (UMEC) 226*32kB (UMEC) 135*64kB (UMEC) 56*128kB (UEC) 33*256kB (UMEC) 12*512kB (UMEC) 5*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 551648kB Aug 27 14:50:31 volumio kernel: 223531 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2096 160 1520 416 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2515 224 2291 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 8545 1208 7337 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 1049 128 921 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1541 96 1445 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2860 448 2412 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1182] 1000 1182 62195 23885 12826 11059 0 839680 0 0 node Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4388 512 3682 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 7116 913 6203 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 10077 2193 7884 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1394] 1000 1394 32982 10193 2232 7961 0 311296 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 10150 2253 7897 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 1471] 102 1471 119475 23715 6649 17066 0 655360 0 0 mpd Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4282] 1000 4282 9165 1085 600 485 0 69632 0 100 (sd-pam) Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=(sd-pam),pid=4282,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 4282 ((sd-pam)) total-vm:36660kB, anon-rss:2400kB, file-rss:1940kB, shmem-rss:0kB, UID:1000 pgtables:68kB oom_score_adj:100 Aug 27 14:50:31 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:50:31 volumio kernel: CPU: 3 UID: 1000 PID: 4443 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:50:31 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:50:31 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:50:31 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:50:31 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:50:31 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:50:31 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:50:31 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:50:31 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:50:31 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:50:31 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:50:31 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:50:31 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:50:31 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:50:31 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:50:31 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:50:31 volumio kernel: Exception stack(0xf0d8dfa8 to 0xf0d8dff0) Aug 27 14:50:31 volumio kernel: dfa0: bed1fb98 00000001 00000003 004e4ac0 00008000 00000001 Aug 27 14:50:31 volumio kernel: dfc0: bed1fb98 00000001 bed1fb98 00000004 0001e6d8 b6f6ad80 00000000 bed1fabc Aug 27 14:50:31 volumio kernel: dfe0: 00042f18 bed1fa38 00016f98 b6ea37ac Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:40708 isolated_anon:0 active_file:13351 inactive_file:204187 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:17262 slab_unreclaimable:5922 mapped:38285 shmem:1939 pagetables:1475 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:674650 free_pcp:13 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:162832kB active_file:53404kB inactive_file:816748kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:153140kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2448kB pagetables:5900kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:552152kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:52kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 944*4kB (UMEC) 587*8kB (UMEC) 338*16kB (UMEC) 226*32kB (UMEC) 141*64kB (UMEC) 56*128kB (UEC) 34*256kB (UMEC) 12*512kB (UMEC) 5*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 552888kB Aug 27 14:50:31 volumio kernel: 221089 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2096 160 1520 416 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2515 224 2291 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 8545 1208 7337 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 1049 128 921 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1541 96 1445 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2860 448 2412 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1182] 1000 1182 62195 23885 12826 11059 0 839680 0 0 node Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4388 512 3682 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 7116 913 6203 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 10077 2193 7884 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1394] 1000 1394 32982 10193 2232 7961 0 311296 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 10150 2253 7897 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 1471] 102 1471 119475 23715 6649 17066 0 655360 0 0 mpd Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=1182,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 1182 (node) total-vm:248780kB, anon-rss:51304kB, file-rss:44236kB, shmem-rss:0kB, UID:1000 pgtables:820kB oom_score_adj:0 Aug 27 14:50:31 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 128. Aug 27 14:50:31 volumio kernel: systemd-journal invoked oom-killer: gfp_mask=0xcc0(GFP_KERNEL), order=0, oom_score_adj=-250 Aug 27 14:50:31 volumio kernel: CPU: 0 UID: 0 PID: 459 Comm: systemd-journal Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from get_free_pages_noprof+0x1c/0x2c Aug 27 14:50:31 volumio kernel: get_free_pages_noprof from proc_pid_cmdline_read+0x1ac/0x374 Aug 27 14:50:31 volumio kernel: proc_pid_cmdline_read from vfs_read+0xb0/0x310 Aug 27 14:50:31 volumio kernel: vfs_read from ksys_read+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_read from __sys_trace_return+0x0/0x10 Aug 27 14:50:31 volumio kernel: Exception stack(0xf0ab1fa8 to 0xf0ab1ff0) Aug 27 14:50:31 volumio kernel: 1fa0: 00001004 01acb6f0 0000001c 01acb6f0 00001004 00000000 Aug 27 14:50:31 volumio kernel: 1fc0: 00001004 01acb6f0 0000001c 00000003 003ffffe 00000001 bee0b278 ffffffff Aug 27 14:50:31 volumio kernel: 1fe0: b6ae1b80 bee0b248 00000000 b6bcb72c Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:27647 isolated_anon:0 active_file:14350 inactive_file:186884 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:16522 slab_unreclaimable:5922 mapped:32289 shmem:1939 pagetables:1364 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:704396 free_pcp:225 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:110588kB active_file:57400kB inactive_file:747536kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:129156kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2368kB pagetables:5308kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:554208kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:900kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 755*4kB (UMEC) 610*8kB (UMEC) 347*16kB (UMEC) 219*32kB (UMEC) 146*64kB (UMEC) 64*128kB (UEC) 36*256kB (UMEC) 12*512kB (UMEC) 6*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 555116kB Aug 27 14:50:31 volumio kernel: 204809 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2128 160 1520 448 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 6337 1208 5129 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2316 448 1868 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4356 512 3650 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 5036 913 4123 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 7837 2193 5644 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1394] 1000 1394 32982 7921 2232 5689 0 311296 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 7910 2253 5657 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 1471] 102 1471 119475 23299 6649 16650 0 655360 0 0 mpd Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=mpd,pid=1471,uid=102 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 1471 (mpd) total-vm:477900kB, anon-rss:26596kB, file-rss:66600kB, shmem-rss:0kB, UID:102 pgtables:640kB oom_score_adj:0 Aug 27 14:50:31 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:50:31 volumio kernel: CPU: 3 UID: 1000 PID: 4443 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:50:31 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:50:31 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:50:31 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:50:31 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:50:31 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:50:31 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:50:31 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:50:31 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:50:31 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:50:31 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:50:31 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:50:31 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:50:31 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:50:31 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:50:31 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:50:31 volumio kernel: Exception stack(0xf0d8dfa8 to 0xf0d8dff0) Aug 27 14:50:31 volumio kernel: dfa0: bed1fb98 00000001 00000003 004e4ac0 00008000 00000001 Aug 27 14:50:31 volumio kernel: dfc0: bed1fb98 00000001 bed1fb98 00000004 0001e6d8 b6f6ad80 00000000 bed1fabc Aug 27 14:50:31 volumio kernel: dfe0: 00042f18 bed1fa38 00016f98 b6ea37ac Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:20987 isolated_anon:0 active_file:14350 inactive_file:179871 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:16040 slab_unreclaimable:5922 mapped:19783 shmem:1939 pagetables:1142 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:718131 free_pcp:16 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:83800kB active_file:57400kB inactive_file:719484kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:79132kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2328kB pagetables:4568kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:557236kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:80kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 972*4kB (UMEC) 646*8kB (UMEC) 368*16kB (UMEC) 224*32kB (UMEC) 147*64kB (UMEC) 63*128kB (UEC) 39*256kB (UMEC) 13*512kB (UMEC) 6*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 557984kB Aug 27 14:50:31 volumio kernel: 197816 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2128 160 1520 448 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 6337 1208 5129 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2316 448 1868 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4356 512 3650 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 5036 913 4123 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 7837 2193 5644 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1394] 1000 1394 32982 7921 2232 5689 0 311296 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 7910 2253 5657 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=1394,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 1394 (node) total-vm:131928kB, anon-rss:8928kB, file-rss:22756kB, shmem-rss:0kB, UID:1000 pgtables:304kB oom_score_adj:0 Aug 27 14:50:31 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:50:31 volumio kernel: CPU: 0 UID: 1000 PID: 4443 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:50:31 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:50:31 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:50:31 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:50:31 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:50:31 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:50:31 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:50:31 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:50:31 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:50:31 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:50:31 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:50:31 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:50:31 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:50:31 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:50:31 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:50:31 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:50:31 volumio kernel: Exception stack(0xf0d8dfa8 to 0xf0d8dff0) Aug 27 14:50:31 volumio kernel: dfa0: bed1fb98 00000001 00000003 004e4ac0 00008000 00000001 Aug 27 14:50:31 volumio kernel: dfc0: bed1fb98 00000001 bed1fb98 00000004 0001e6d8 b6f6ad80 00000000 bed1fabc Aug 27 14:50:31 volumio kernel: dfe0: 00042f18 bed1fa38 00016f98 b6ea37ac Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:18730 isolated_anon:0 active_file:14350 inactive_file:175680 isolated_file:32 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:15781 slab_unreclaimable:5885 mapped:19746 shmem:1939 pagetables:1105 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:725439 free_pcp:31 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:74920kB active_file:57400kB inactive_file:702720kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:78984kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2248kB pagetables:4420kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:558244kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:128kB local_pcp:32kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 961*4kB (UMEC) 653*8kB (UMEC) 374*16kB (UMEC) 230*32kB (UMEC) 155*64kB (UMEC) 64*128kB (UEC) 38*256kB (UMEC) 14*512kB (UMEC) 6*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 559180kB Aug 27 14:50:31 volumio kernel: 193598 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2128 160 1520 448 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 6337 1208 5129 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2316 448 1868 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4356 512 3650 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 5036 913 4123 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 7837 2193 5644 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1395] 1000 1395 32976 7910 2253 5657 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=1395,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 1395 (node) total-vm:131904kB, anon-rss:9012kB, file-rss:22628kB, shmem-rss:0kB, UID:1000 pgtables:296kB oom_score_adj:0 Aug 27 14:50:31 volumio kernel: systemd-journal invoked oom-killer: gfp_mask=0xcc0(GFP_KERNEL), order=0, oom_score_adj=-250 Aug 27 14:50:31 volumio kernel: CPU: 0 UID: 0 PID: 459 Comm: systemd-journal Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from get_free_pages_noprof+0x1c/0x2c Aug 27 14:50:31 volumio kernel: get_free_pages_noprof from proc_pid_cmdline_read+0x1ac/0x374 Aug 27 14:50:31 volumio kernel: proc_pid_cmdline_read from vfs_read+0xb0/0x310 Aug 27 14:50:31 volumio kernel: vfs_read from ksys_read+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_read from __sys_trace_return+0x0/0x10 Aug 27 14:50:31 volumio kernel: Exception stack(0xf0ab1fa8 to 0xf0ab1ff0) Aug 27 14:50:31 volumio kernel: 1fa0: 00001004 01acb6f0 0000001c 01acb6f0 00001004 00000000 Aug 27 14:50:31 volumio kernel: 1fc0: 00001004 01acb6f0 0000001c 00000003 003ffffe 00000001 bee0b278 ffffffff Aug 27 14:50:31 volumio kernel: 1fe0: b6ae1b80 bee0b248 00000000 b6bcb72c Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:16436 isolated_anon:0 active_file:14350 inactive_file:172418 isolated_file:96 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:15559 slab_unreclaimable:5885 mapped:19708 shmem:1939 pagetables:1031 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:731865 free_pcp:7 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:65744kB active_file:57400kB inactive_file:689672kB unevictable:16380kB isolated(anon):0kB isolated(file):384kB mapped:78832kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2168kB pagetables:4124kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:559756kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:28kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 956*4kB (UMEC) 657*8kB (UMEC) 378*16kB (UMEC) 235*32kB (UMEC) 157*64kB (UMEC) 68*128kB (UEC) 38*256kB (UMEC) 14*512kB (UMEC) 6*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 560056kB Aug 27 14:50:31 volumio kernel: 190416 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2128 160 1520 448 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 6337 1208 5129 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2316 448 1868 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4356 512 3650 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 5036 913 4123 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1393] 1000 1393 32918 7837 2193 5644 0 303104 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=1393,uid=1000 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 1393 (node) total-vm:131672kB, anon-rss:8772kB, file-rss:22576kB, shmem-rss:0kB, UID:1000 pgtables:296kB oom_score_adj:0 Aug 27 14:50:31 volumio kernel: systemd-journal invoked oom-killer: gfp_mask=0xcc0(GFP_KERNEL), order=0, oom_score_adj=-250 Aug 27 14:50:31 volumio kernel: CPU: 0 UID: 0 PID: 459 Comm: systemd-journal Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:50:31 volumio kernel: Tainted: [C]=CRAP Aug 27 14:50:31 volumio kernel: Hardware name: BCM2711 Aug 27 14:50:31 volumio kernel: Call trace: Aug 27 14:50:31 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:50:31 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:50:31 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:50:31 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:50:31 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:50:31 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:50:31 volumio kernel: __alloc_pages_noprof from get_free_pages_noprof+0x1c/0x2c Aug 27 14:50:31 volumio kernel: get_free_pages_noprof from proc_pid_cmdline_read+0x1ac/0x374 Aug 27 14:50:31 volumio kernel: proc_pid_cmdline_read from vfs_read+0xb0/0x310 Aug 27 14:50:31 volumio kernel: vfs_read from ksys_read+0x68/0xec Aug 27 14:50:31 volumio kernel: ksys_read from __sys_trace_return+0x0/0x10 Aug 27 14:50:31 volumio kernel: Exception stack(0xf0ab1fa8 to 0xf0ab1ff0) Aug 27 14:50:31 volumio kernel: 1fa0: 00001004 01acb6f0 0000001c 01acb6f0 00001004 00000000 Aug 27 14:50:31 volumio kernel: 1fc0: 00001004 01acb6f0 0000001c 00000003 003ffffe 00000001 bee0b278 ffffffff Aug 27 14:50:31 volumio kernel: 1fe0: b6ae1b80 bee0b248 00000000 b6bcb72c Aug 27 14:50:31 volumio kernel: Mem-Info: Aug 27 14:50:31 volumio kernel: active_anon:286 inactive_anon:14179 isolated_anon:0 active_file:14350 inactive_file:171438 isolated_file:96 unevictable:4095 dirty:6639 writeback:0 slab_reclaimable:15522 slab_unreclaimable:5885 mapped:19005 shmem:1939 pagetables:957 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:734889 free_pcp:11 free_cma:128618 Aug 27 14:50:31 volumio kernel: Node 0 active_anon:1144kB inactive_anon:56716kB active_file:57400kB inactive_file:685752kB unevictable:16380kB isolated(anon):0kB isolated(file):384kB mapped:76020kB dirty:26556kB writeback:0kB shmem:7756kB writeback_tmp:0kB kernel_stack:2168kB pagetables:3828kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:50:31 volumio kernel: DMA free:559756kB boost:32768kB min:49152kB low:53248kB high:57344kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:2828kB inactive_file:120kB unevictable:0kB writepending:60kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:44kB local_pcp:0kB free_cma:514472kB Aug 27 14:50:31 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:50:31 volumio kernel: DMA: 968*4kB (UMEC) 659*8kB (UMEC) 378*16kB (UMEC) 234*32kB (UMEC) 156*64kB (UMEC) 68*128kB (UEC) 39*256kB (UMEC) 14*512kB (UMEC) 6*1024kB (MC) 2*2048kB (C) 120*4096kB (C) = 560280kB Aug 27 14:50:31 volumio kernel: 189343 total pagecache pages Aug 27 14:50:31 volumio kernel: 0 pages in swap cache Aug 27 14:50:31 volumio kernel: Free swap = 0kB Aug 27 14:50:31 volumio kernel: Total swap = 0kB Aug 27 14:50:31 volumio kernel: 999424 pages RAM Aug 27 14:50:31 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:50:31 volumio kernel: 30869 pages reserved Aug 27 14:50:31 volumio kernel: 131072 pages cma reserved Aug 27 14:50:31 volumio kernel: Tasks state (memory values in pages): Aug 27 14:50:31 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:50:31 volumio kernel: [ 459] 0 459 7573 2128 160 1520 448 61440 0 -250 systemd-journal Aug 27 14:50:31 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:50:31 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:50:31 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:50:31 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:50:31 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:50:31 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:50:31 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:50:31 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:50:31 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:50:31 volumio kernel: [ 802] 0 802 32156 6337 1208 5129 0 266240 0 0 node Aug 27 14:50:31 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:50:31 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:50:31 volumio kernel: [ 1034] 104 1034 37337 2316 448 1868 0 143360 0 0 shairport-sync Aug 27 14:50:31 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:50:31 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:50:31 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:50:31 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:50:31 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:50:31 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:50:31 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:50:31 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:50:31 volumio kernel: [ 1240] 0 1240 17917 3942 480 3417 45 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:50:31 volumio kernel: [ 1299] 0 1299 17977 3604 486 3075 43 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1314] 0 1314 19146 4356 512 3650 194 118784 0 0 smbd Aug 27 14:50:31 volumio kernel: [ 1326] 0 1326 18753 2659 503 2156 0 106496 0 0 smbd-notifyd Aug 27 14:50:31 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:50:31 volumio kernel: [ 1338] 0 1338 17917 2785 488 2297 0 114688 0 0 winbindd Aug 27 14:50:31 volumio kernel: [ 1352] 1000 1352 1400 647 32 615 0 32768 0 0 network_monitor Aug 27 14:50:31 volumio kernel: [ 1378] 1000 1378 31843 5036 913 4123 0 233472 0 0 node Aug 27 14:50:31 volumio kernel: [ 1449] 0 1449 159116 5210 825 4385 0 143360 0 0 volumio5-onboar Aug 27 14:50:31 volumio kernel: [ 4278] 0 4278 3669 2290 256 2034 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4420] 0 4420 3669 2259 256 2003 0 53248 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4442] 1000 4442 4507 2334 1051 1283 0 57344 0 0 sshd Aug 27 14:50:31 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:50:31 volumio kernel: [ 4704] 1000 4704 1115 381 0 381 0 36864 0 0 sleep Aug 27 14:50:31 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=802,uid=0 Aug 27 14:50:31 volumio kernel: Out of memory: Killed process 802 (node) total-vm:128624kB, anon-rss:4832kB, file-rss:20516kB, shmem-rss:0kB, UID:0 pgtables:260kB oom_score_adj:0 Aug 27 14:50:31 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:31 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:31.792+09:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 27 14:50:31 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:31.873+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:31 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:31 volumio systemd[1]: wireless.service: Main process exited, code=killed, status=9/KILL Aug 27 14:50:31 volumio systemd[1]: wireless.service: Failed with result 'signal'. Aug 27 14:50:31 volumio systemd[1]: wireless.service: Consumed 8min 59.482s CPU time. Aug 27 14:50:31 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=9/KILL Aug 27 14:50:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=9/KILL Aug 27 14:50:31 volumio systemd[1]: mpd.service: Failed with result 'signal'. Aug 27 14:50:31 volumio systemd[1]: mpd.service: Consumed 9.623s CPU time. Aug 27 14:50:31 volumio systemd[1]: user@1000.service: Main process exited, code=killed, status=9/KILL Aug 27 14:50:31 volumio systemd[1]: user@1000.service: Failed with result 'signal'. Aug 27 14:50:31 volumio volumio-remote-updater[801]: [2026-08-27 14:50:31] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 27 14:50:31 volumio volumio-remote-updater[801]: [2026-08-27 14:50:31] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 27 14:50:31 volumio systemd[1]: volumio.service: Failed with result 'signal'. Aug 27 14:50:31 volumio systemd[1]: volumio.service: Consumed 9min 37.131s CPU time. Aug 27 14:50:31 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 27 14:50:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 27 14:50:32 volumio upmpdcli[4724]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:50:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Aug 27 14:50:32 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:50:32 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:50:32 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 27 14:50:32 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 27 14:50:32 volumio systemd[1]: volumio.service: Consumed 9min 37.131s CPU time. Aug 27 14:50:32 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Aug 27 14:50:32 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 27 14:50:32 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:32.876+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:33 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:33.901+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:34 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:34.903+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:35 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:35.905+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:36 volumio volumio5-onboarding[1449]: time=2026-08-27T14:50:36.907+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:50:36 volumio volumio-remote-updater[801]: [2026-08-27 14:50:36] [info] asio async_connect error: asio.system:111 (Connection refused) Aug 27 14:50:36 volumio volumio-remote-updater[801]: [2026-08-27 14:50:36] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Aug 27 14:50:36 volumio volumio-remote-updater[801]: [2026-08-27 14:50:36] [error] handle_connect error: Connection refused Aug 27 14:50:37 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:37 volumio volumio[4739]: info: ----- Volumio3 ---- Aug 27 14:50:37 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:37 volumio volumio[4739]: info: ----- System startup ---- Aug 27 14:50:37 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:38 volumio volumio[4739]: info: MYVOLUMIO Environment detected Aug 27 14:50:38 volumio volumio[4739]: info: Plugin folders cleanup Aug 27 14:50:38 volumio volumio[4739]: info: Scanning into folder /volumio/app/plugins/ Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category audio_interface Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category miscellanea Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category music_service Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category plugins.json Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category system_controller Aug 27 14:50:38 volumio volumio[4739]: info: Scanning category user_interface Aug 27 14:50:38 volumio volumio[4739]: info: Scanning into folder /data/plugins/ Aug 27 14:50:38 volumio volumio[4739]: info: Plugin folders cleanup completed Aug 27 14:50:38 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:38 volumio volumio[4739]: info: ----- Core plugins startup ---- Aug 27 14:50:38 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:38 volumio volumio[4739]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 14:50:38 volumio volumio[4739]: info: Adding plugin upnp to MyMusic Plugins Aug 27 14:50:38 volumio volumio[4739]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 14:50:38 volumio volumio[4739]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 14:50:38 volumio volumio[4739]: info: Loading plugins from folder /data/plugins/ Aug 27 14:50:38 volumio volumio[4739]: info: Loading plugin "system"... Aug 27 14:50:38 volumio volumio[4739]: info: Loading plugin "appearance"... Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "network"... Aug 27 14:50:39 volumio volumio[4739]: info: Refreshing Cached IP Addresses Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "services"... Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "volumio5onboarding"... Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "alsa_controller"... Aug 27 14:50:39 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "wizard"... Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "networkfs"... Aug 27 14:50:39 volumio sudo[4770]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 14:50:39 volumio sudo[4768]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 14:50:39 volumio sudo[4768]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:39 volumio sudo[4770]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:39 volumio sudo[4768]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:39 volumio sudo[4770]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:39 volumio volumio[4739]: info: Starting Udev Watcher for removable devices Aug 27 14:50:39 volumio volumio[4739]: info: Ignoring mount for partition: boot Aug 27 14:50:39 volumio volumio[4739]: info: Ignoring mount for partition: volumio Aug 27 14:50:39 volumio volumio[4739]: info: Ignoring mount for partition: volumio_data Aug 27 14:50:39 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "volumio_command_line_client"... Aug 27 14:50:39 volumio volumio[4739]: info: Plugin upnp is not enabled Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "my_music"... Aug 27 14:50:39 volumio volumio[4739]: info: Loading plugin "mpd"... Aug 27 14:50:40 volumio volumio[4739]: info: Plugin upnp_browser is not enabled Aug 27 14:50:40 volumio volumio[4739]: info: Loading plugin "alarm-clock"... Aug 27 14:50:40 volumio volumio[4739]: info: Plugin airplay_emulation is not enabled Aug 27 14:50:40 volumio volumio[4739]: info: Loading plugin "last_100"... Aug 27 14:50:40 volumio volumio[4739]: info: Loading plugin "webradio"... Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "i2s_dacs"... Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "volumiodiscovery"... Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** For more information see Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:50:43 volumio volumio[4739]: *** WARNING *** For more information see Aug 27 14:50:43 volumio node[4739]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 14:50:43 volumio node[4739]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:50:43 volumio node[4739]: *** WARNING *** For more information see Aug 27 14:50:43 volumio node[4739]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 14:50:43 volumio node[4739]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:50:43 volumio node[4739]: *** WARNING *** For more information see Aug 27 14:50:43 volumio volumio[4739]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 14:50:43 volumio volumio[4739]: info: Discovery: Started advertising with name: Volumio Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "outputs"... Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "albumart"... Aug 27 14:50:43 volumio volumio[4739]: info: Plugin example_plugin is not enabled Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "inputs"... Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "updater_comm"... Aug 27 14:50:43 volumio volumio[4739]: info: Plugin mpdemulation is not enabled Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "rest_api"... Aug 27 14:50:43 volumio volumio[4739]: info: Loading plugin "websocket"... Aug 27 14:50:43 volumio volumio[4739]: info: Starting Socket.io Server version 1.7.4 Aug 27 14:50:43 volumio volumio[4739]: info: Loading i18n strings for locale ko Aug 27 14:50:43 volumio volumio[4739]: Updating browse sources language Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:50:43 volumio volumio[4797]: Forking 3 albumart workers Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::initPlayerControls Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:50:43 volumio volumio[4739]: Express server listening on port 3000 Aug 27 14:50:43 volumio volumio[4739]: [Metrics] WebUI: 6s 857.20ms Aug 27 14:50:43 volumio volumio[4739]: info: CoreStateMachine::resetVolumioState Aug 27 14:50:43 volumio volumio[4739]: info: CoreStateMachine::getcurrentVolume Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:50:43 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:50:43 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:50:43 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:50:44 volumio volumio[4739]: info: Volumio Network Manager: Network status updated: 1 Aug 27 14:50:44 volumio volumio[4739]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.255.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 27 14:50:44 volumio volumio[4739]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.255.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:50:44 volumio volumio[4739]: info: Reloading queue from file Aug 27 14:50:44 volumio volumio[4739]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 27 14:50:44 volumio volumio[4739]: info: CoreStateMachine::setRepeat true single undefined Aug 27 14:50:44 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:50:44 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:50:44 volumio volumio[4739]: info: CoreStateMachine::setRandom true Aug 27 14:50:44 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:50:44 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:50:44 volumio volumio[4739]: info: Setting Device type: Raspberry PI Aug 27 14:50:44 volumio volumio[4739]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 27 14:50:44 volumio volumio[4739]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Aug 27 14:50:44 volumio volumio[4739]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 27 14:50:44 volumio volumio[4739]: info: Completed loading Core Plugins Aug 27 14:50:44 volumio volumio[4739]: info: Preparing to generate the ALSA configuration file Aug 27 14:50:44 volumio volumio[4739]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 27 14:50:44 volumio volumio-remote-updater[801]: [2026-08-27 14:50:44] [connect] Successful connection Aug 27 14:50:44 volumio volumio[4739]: info: Asound.conf file unchanged, so no further update is needed Aug 27 14:50:44 volumio volumio[4739]: info: Output device has changed, restarting MPD Aug 27 14:50:44 volumio volumio[4739]: info: ___________ START PLUGINS ___________ Aug 27 14:50:44 volumio volumio[4739]: info: ControllerMpd::onStart: Initializing MPD Aug 27 14:50:44 volumio volumio[4739]: info: Creating MPD Configuration file Aug 27 14:50:44 volumio sudo[4850]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:50:44 volumio sudo[4850]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:44 volumio sudo[4850]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 14:50:44 volumio volumio[4739]: info: [1787809844543] CoreMusicLibrary::Adding element Last_100 Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:50:44 volumio sudo[4852]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 14:50:44 volumio sudo[4852]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:44 volumio volumio[4739]: info: [1787809844554] CoreMusicLibrary::Adding element Webradio Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:50:44 volumio volumio[4739]: info: Initializing BBC Radios Aug 27 14:50:44 volumio sudo[4859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:50:44 volumio sudo[4857]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:50:44 volumio sudo[4859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:44 volumio sudo[4857]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:44 volumio sudo[4857]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:44 volumio sudo[4854]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 14:50:44 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:50:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 14:50:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 14:50:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:50:44 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:50:44 volumio volumio[4739]: info: Volumio Calling Home Aug 27 14:50:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 14:50:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 14:50:44 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:44 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 14:50:44 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 14:50:44 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 14:50:44 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 14:50:44 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 14:50:44 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 14:50:44 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 14:50:45 volumio sudo[4882]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:50:45 volumio sudo[4882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 14:50:45 volumio sudo[4882]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:45 volumio volumio[4809]: Starting albumart workers Aug 27 14:50:45 volumio volumio[4807]: Starting albumart workers Aug 27 14:50:45 volumio volumio[4739]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 3 Aug 27 14:50:45 volumio volumio[4739]: info: Discovery: adding f8cba9ce-b227-4dfa-99d4-964e5127c5ee Aug 27 14:50:45 volumio volumio[4739]: info: Discovery: Found device Volumio Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::volumioGetState Aug 27 14:50:45 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:45 volumio volumio-remote-updater[801]: [2026-08-27 14:50:45] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787809844 101 Aug 27 14:50:45 volumio volumio[4739]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 4 Aug 27 14:50:45 volumio volumio[4739]: info: MPD Permissions set Aug 27 14:50:45 volumio volumio[4739]: info: MPD Permissions set Aug 27 14:50:45 volumio volumio[4808]: Starting albumart workers Aug 27 14:50:45 volumio volumio[4739]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:50:45 volumio volumio[4739]: info: Discovery: this is already registered, f8cba9ce-b227-4dfa-99d4-964e5127c5ee Aug 27 14:50:45 volumio volumio[4739]: info: Discovery: Found device Volumio Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::volumioGetState Aug 27 14:50:45 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:45 volumio volumio[4739]: info: CoreCommandRouter::volumioGetState Aug 27 14:50:45 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:45 volumio volumio[4739]: info: Volumio called home Aug 27 14:50:46 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 14:50:46 volumio volumio[4739]: info: Listing playlists Aug 27 14:50:46 volumio volumio[4739]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 14:50:46 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:50:46 volumio volumio[4739]: info: CoreCommandRouter::volumioGetQueue Aug 27 14:50:46 volumio volumio[4739]: info: CoreStateMachine::getQueue Aug 27 14:50:46 volumio volumio[4739]: info: CorePlayQueue::getQueue Aug 27 14:50:46 volumio volumio[4739]: info: CoreCommandRouter::volumioGetState Aug 27 14:50:46 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:50:47 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 129. Aug 27 14:50:47 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:47 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:50:47 volumio upmpdcli[4917]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:50:47 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:50:47 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:50:50 volumio mpd[4885]: 2026-08-27T14:50:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 14:50:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 14:50:50 volumio sudo[4852]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:50 volumio sudo[4859]: pam_unix(sudo:session): session closed for user root Aug 27 14:50:50 volumio volumio[4739]: info: Completed starting Core Plugins Aug 27 14:50:50 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:50 volumio volumio[4739]: info: ----- MyVolumio plugins startup ---- Aug 27 14:50:50 volumio volumio[4739]: info: ------------------------------------------- Aug 27 14:50:50 volumio volumio[4739]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 27 14:50:50 volumio volumio[4739]: error: MPD error: The expression evaluated to a falsy value: Aug 27 14:50:50 volumio volumio[4739]: assert.ok(self.idling) Aug 27 14:50:50 volumio volumio[4739]: error: The expression evaluated to a falsy value: Aug 27 14:50:50 volumio volumio[4739]: assert.ok(self.idling) Aug 27 14:50:50 volumio volumio[4739]: info: MPD running with PID4885 Aug 27 14:50:50 volumio volumio[4739]: ,establishing connection Aug 27 14:50:50 volumio volumio[4739]: error: updateQueue error: null Aug 27 14:50:50 volumio volumio[4739]: error: updateQueue error: null Aug 27 14:50:55 volumio volumio[4739]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Aug 27 14:50:59 volumio volumio[4739]: info: Adding plugin bluetooth to MyMusic Plugins Aug 27 14:50:59 volumio volumio[4739]: info: Adding plugin multiroom to MyMusic Plugins Aug 27 14:50:59 volumio volumio[4739]: info: Adding plugin metavolumio to MyMusic Plugins Aug 27 14:50:59 volumio volumio[4739]: info: Adding plugin cd_controller to MyMusic Plugins Aug 27 14:51:00 volumio volumio[4739]: info: Adding plugin qobuzconnect to MyMusic Plugins Aug 27 14:51:00 volumio volumio[4739]: info: Adding plugin smart_inputs to MyMusic Plugins Aug 27 14:51:00 volumio volumio[4739]: info: Adding plugin tidalconnect to MyMusic Plugins Aug 27 14:51:00 volumio volumio[4739]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Aug 27 14:51:02 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:02 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:02 volumio volumio[4739]: info: Starting MyVolumio Remote Streaming Endpoints Aug 27 14:51:02 volumio volumio[4739]: info: MyVolumio login type: Token Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Aug 27 14:51:02 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 130. Aug 27 14:51:02 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:51:02 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:51:02 volumio upmpdcli[4951]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:51:02 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:51:02 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Aug 27 14:51:02 volumio volumio[4739]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Aug 27 14:51:02 volumio volumio[4739]: info: Streaming services startup Aug 27 14:51:02 volumio volumio[4739]: info: Starting Streaming Daemon Aug 27 14:51:03 volumio sudo[4953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 14:51:03 volumio sudo[4953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:03 volumio volumio[4739]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Aug 27 14:51:03 volumio sudo[4953]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:03 volumio volumio[4739]: error: Cannot start Volumio Streaming Daemon Aug 27 14:51:03 volumio volumio[4739]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 14:51:03 volumio volumio[4739]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 14:51:04 volumio volumio[4739]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Aug 27 14:51:06 volumio volumio[4739]: info: MyVolumio token set successfully Aug 27 14:51:06 volumio volumio[4739]: info: MYVOLUMIO: Adding device Aug 27 14:51:06 volumio volumio[4739]: info: MYVOLUMIO: Evaluating Server Aug 27 14:51:06 volumio volumio[4739]: info: MyVolumio status changed Aug 27 14:51:06 volumio volumio[4739]: info: Streaming services startup Aug 27 14:51:06 volumio volumio[4739]: info: Starting Streaming Daemon Aug 27 14:51:06 volumio volumio[4739]: info: Removing browser output: myVolumio user plan is not superstar Aug 27 14:51:06 volumio volumio[4739]: info: Removing audio output: Aug 27 14:51:06 volumio volumio[4739]: info: Stoppping Tunnel 1 Aug 27 14:51:06 volumio sudo[4980]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Aug 27 14:51:06 volumio sudo[4980]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:06 volumio sudo[4982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Aug 27 14:51:06 volumio sudo[4982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:06 volumio sudo[4980]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:06 volumio volumio[4739]: error: Cannot start Volumio Streaming Daemon Aug 27 14:51:06 volumio volumio[4739]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Aug 27 14:51:06 volumio volumio[4739]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Aug 27 14:51:06 volumio sudo[4982]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:06 volumio volumio[4739]: info: Remote SSH Stopped Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPlay Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::play index undefined Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::startPlaybackTimer Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand stop took 1 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand clear took 2 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac" Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand add "INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac" took 3 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand play Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 9ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 9ms Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand play took 7 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 6ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 4ms Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:09 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 3ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 2ms Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 44 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 55 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 47 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: info: Aug 27 14:51:09 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 12 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 10 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 7 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:09 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":756,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:09 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:09 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:09 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:09 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 100ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 120ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 119ms Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand status took 62 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 27 14:51:09 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 54 milliseconds Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:09 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:09 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:09 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:09 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:09 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:09 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:09 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 118ms Aug 27 14:51:09 volumio volumio[4739]: info: ------------------------------ 116ms Aug 27 14:51:10 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 78 milliseconds Aug 27 14:51:10 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:10 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:10 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:10 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:10 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"518 Kbps","isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:10 volumio volumio[4739]: verbose: CURRENT POSITION 0 Aug 27 14:51:10 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:10 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:10 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:10 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:10 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:10 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:10 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:10 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:10 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:10 volumio volumio[4739]: info: ------------------------------ 163ms Aug 27 14:51:11 volumio volumio[4739]: info: Preload queue cleared Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::ClearQueue Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::stPlaybackTimer Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::updateTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::serviceStop Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::serviceStop Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::stop Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::clearPlayQueue Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::saveQueue Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushQueue Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::addQueueItems Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::addQueueItems Aug 27 14:51:11 volumio volumio[4739]: info: Preload queue cleared Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Trains%20and%20Winter%20Rains.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20My!%20My!%20Time%20Flies!%20(album).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand stop took 31 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushQueue Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::saveQueue Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::updateTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPlay Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::play index 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::addQueueItems Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::addQueueItems Aug 27 14:51:11 volumio volumio[4739]: info: Preload queue cleared Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Stars and Midnight Blue (album).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Stars and Midnight Blue (album).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Amarantine (album verson).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Amarantine (album verson).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Sumiregusa (wild violet).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Sumiregusa (wild violet).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - The River Sings.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - The River Sings.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - If I Could Be Where You Are.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - If I Could Be Where You Are.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Wild Child (영화 냉정과 열정사이 OST).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Wild Child (영화 냉정과 열정사이 OST).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Only Time (original version).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Only Time (original version).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Drifting.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Drifting.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Flora's Secret.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Flora's Secret.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Fallen Embers.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Fallen Embers.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - One By One.flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - One By One.flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Pax Deorum (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Pax Deorum (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Athair ar Neamh (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Athair ar Neamh (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Anywhere Is (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Anywhere Is (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Orinoco Flow (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Orinoco Flow (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Watermark (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Watermark (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Boadicea (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Boadicea (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - May It Be (노스페이스 CF 광고).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - May It Be (노스페이스 CF 광고).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Carribbean Blue (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Carribbean Blue (Rbmastered 2009).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Aniron (I Desire).flac Aug 27 14:51:11 volumio volumio[4739]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Aniron (I Desire).flac in service mpd Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::play index undefined Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::startPlaybackTimer Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand stop Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Stars%20and%20Midnight%20Blue%20(album).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Stars and Midnight Blue (album).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Amarantine%20(album%20verson).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Amarantine (album verson).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Sumiregusa%20(wild%20violet).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Sumiregusa (wild violet).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20The%20River%20Sings.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - The River Sings.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20If%20I%20Could%20Be%20Where%20You%20Are.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - If I Could Be Where You Are.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Wild%20Child%20(%EC%98%81%ED%99%94%20%EB%83%89%EC%A0%95%EA%B3%BC%20%EC%97%B4%EC%A0%95%EC%82%AC%EC%9D%B4%20OST).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Wild Child (영화 냉정과 열정사이 OST).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Only%20Time%20(original%20version).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Only Time (original version).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Drifting.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Drifting.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Flora's%20Secret.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Flora's Secret.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Fallen%20Embers.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Fallen Embers.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20One%20By%20One.flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - One By One.flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Pax%20Deorum%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Pax Deorum (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Athair%20ar%20Neamh%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Athair ar Neamh (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Anywhere%20Is%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Anywhere Is (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Orinoco%20Flow%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Orinoco Flow (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Watermark%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Watermark (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Boadicea%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Boadicea (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20May%20It%20Be%20(%EB%85%B8%EC%8A%A4%ED%8E%98%EC%9D%B4%EC%8A%A4%20CF%20%EA%B4%91%EA%B3%A0).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - May It Be (노스페이스 CF 광고).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Carribbean%20Blue%20(Rbmastered%202009).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Carribbean Blue (Rbmastered 2009).flac Aug 27 14:51:11 volumio volumio[4739]: info: ALBUMART /albumart?cacheid=335&path=%2Fmnt%2FINTERNAL%2FEnya%20%EC%97%94%EC%95%BC%5B%EC%A0%84%EC%A7%91.%EB%A6%AC%EB%A7%88%EC%8A%A4%ED%84%B0%EB%A7%81.Best%5D%EC%95%A8%EB%B2%94%2F%EC%97%94%EC%95%BC%20-%20Aniron%20(I%20Desire).flac&metadata=false Aug 27 14:51:11 volumio volumio[4739]: info: URI /mnt/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Aniron (I Desire).flac Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand status took 50 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand stop took 12 milliseconds Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand clear Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushQueue Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::saveQueue Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::updateTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrackBlock Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 22 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand clear took 22 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand status took 6 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand status took 4 milliseconds Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac" Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Trains and Winter Rains","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac","trackType":"flac"} Aug 27 14:51:11 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState stateService stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: No code Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 27 14:51:11 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState stateService stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: No code Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Aug 27 14:51:11 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState stateService stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: No code Aug 27 14:51:11 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:11 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:11 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 136ms Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 64ms Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 63ms Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: error: updateQueue error: null Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 73ms Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand add "INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac" took 71 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 14ms Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 13ms Aug 27 14:51:11 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand play Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: info: Aug 27 14:51:11 volumio volumio[4739]: ---------------------------- MPD announces system playlist update Aug 27 14:51:11 volumio volumio[4739]: info: Ignoring MPD Status Update Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 8ms Aug 27 14:51:11 volumio volumio[4739]: info: sendMpdCommand play took 6 milliseconds Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 5ms Aug 27 14:51:11 volumio volumio[4739]: info: ------------------------------ 2ms Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 6 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 6 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 4 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 4 milliseconds Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 6 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus stop Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":573,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:12 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":756,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:12 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"495 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:12 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 36ms Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 54ms Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 54ms Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 52ms Aug 27 14:51:12 volumio volumio[4739]: info: Aug 27 14:51:12 volumio volumio[4739]: ---------------------------- MPD announces state update: player Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::getState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand status Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 43 milliseconds Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand status took 2 milliseconds Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseState Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"557 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:12 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 67ms Aug 27 14:51:12 volumio volumio[4739]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 27 14:51:12 volumio volumio[4739]: verbose: ControllerMpd::parseTrackInfo Aug 27 14:51:12 volumio volumio[4739]: info: ControllerMpd::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::servicePushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: verbose: STATE SERVICE {"status":"play","position":0,"seek":871,"duration":181,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"557 Kbps","isStreaming":false,"title":"My! My! Time Flies!","artist":"Enya","album":"The Very Best Of","uri":"INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - My! My! Time Flies! (album).flac","trackType":"flac"} Aug 27 14:51:12 volumio volumio[4739]: verbose: CURRENT POSITION 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState stateService play Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::syncState currentStatus play Aug 27 14:51:12 volumio volumio[4739]: info: Received an update from plugin. extracting info from payload Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: CoreStateMachine::pushState Aug 27 14:51:12 volumio volumio[4739]: info: CorePlayQueue::getTrack 1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:12 volumio volumio[4739]: info: ------------------------------ 50ms Aug 27 14:51:12 volumio volumio[4739]: info: Setting Geolocation for MyVolumio to as1 Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:12 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:12 volumio volumio[4739]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Aug 27 14:51:13 volumio volumio[4739]: info: Updating MyVolumio device info Aug 27 14:51:13 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:13 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:13 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:13 volumio volumio[4739]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Aug 27 14:51:14 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:51:14 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:14 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 27 14:51:14 volumio volumio-remote-updater[801]: Test mode disabled Aug 27 14:51:14 volumio volumio-remote-updater[801]: Alpha mode disabled Aug 27 14:51:14 volumio volumio-remote-updater[801]: Alpha legacy test mode disabled Aug 27 14:51:14 volumio volumio[4739]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Aug 27 14:51:15 volumio kernel: sftp-server invoked oom-killer: gfp_mask=0x40cd0(GFP_KERNEL|__GFP_COMP|__GFP_RECLAIMABLE), order=0, oom_score_adj=0 Aug 27 14:51:15 volumio kernel: CPU: 0 UID: 1000 PID: 4444 Comm: sftp-server Tainted: G C 6.12.74-v7l+ #1948 Aug 27 14:51:15 volumio kernel: Tainted: [C]=CRAP Aug 27 14:51:15 volumio kernel: Hardware name: BCM2711 Aug 27 14:51:15 volumio kernel: Call trace: Aug 27 14:51:15 volumio kernel: unwind_backtrace from show_stack+0x18/0x1c Aug 27 14:51:15 volumio kernel: show_stack from dump_stack_lvl+0x5c/0x80 Aug 27 14:51:15 volumio kernel: dump_stack_lvl from dump_header+0x50/0x180 Aug 27 14:51:15 volumio kernel: dump_header from oom_kill_process+0x230/0x2a0 Aug 27 14:51:15 volumio kernel: oom_kill_process from out_of_memory+0x104/0x34c Aug 27 14:51:15 volumio kernel: out_of_memory from __alloc_pages_noprof+0xa58/0x1028 Aug 27 14:51:15 volumio kernel: __alloc_pages_noprof from new_slab+0x390/0x44c Aug 27 14:51:15 volumio kernel: new_slab from ___slab_alloc+0x570/0x958 Aug 27 14:51:15 volumio kernel: ___slab_alloc from kmem_cache_alloc_lru_noprof+0x260/0x2ac Aug 27 14:51:15 volumio kernel: kmem_cache_alloc_lru_noprof from xas_nomem+0x3c/0x90 Aug 27 14:51:15 volumio kernel: xas_nomem from __filemap_add_folio+0x2bc/0x478 Aug 27 14:51:15 volumio kernel: __filemap_add_folio from filemap_add_folio+0xa0/0x138 Aug 27 14:51:15 volumio kernel: filemap_add_folio from __filemap_get_folio+0x118/0x2ec Aug 27 14:51:15 volumio kernel: __filemap_get_folio from ext4_da_write_begin+0xe4/0x294 Aug 27 14:51:15 volumio kernel: ext4_da_write_begin from generic_perform_write+0xc8/0x27c Aug 27 14:51:15 volumio kernel: generic_perform_write from ext4_buffered_write_iter+0x68/0x114 Aug 27 14:51:15 volumio kernel: ext4_buffered_write_iter from do_iter_readv_writev+0x154/0x244 Aug 27 14:51:15 volumio kernel: do_iter_readv_writev from vfs_iter_write+0xa0/0x248 Aug 27 14:51:15 volumio kernel: vfs_iter_write from backing_file_write_iter+0x1d4/0x280 Aug 27 14:51:15 volumio kernel: backing_file_write_iter from ovl_write_iter+0xf0/0x11c [overlay] Aug 27 14:51:15 volumio kernel: ovl_write_iter [overlay] from vfs_write+0x308/0x418 Aug 27 14:51:15 volumio kernel: vfs_write from ksys_write+0x68/0xec Aug 27 14:51:15 volumio kernel: ksys_write from ret_fast_syscall+0x0/0x5c Aug 27 14:51:15 volumio kernel: Exception stack(0xf08d9fa8 to 0xf08d9ff0) Aug 27 14:51:15 volumio kernel: 9fa0: bef51b98 00000001 00000003 0066bac8 00008000 00000001 Aug 27 14:51:15 volumio kernel: 9fc0: bef51b98 00000001 bef51b98 00000004 0001e6d8 b6fefd80 00000000 bef51abc Aug 27 14:51:15 volumio kernel: 9fe0: 00042f18 bef51a38 00016f98 b6f287ac Aug 27 14:51:15 volumio kernel: Mem-Info: Aug 27 14:51:15 volumio kernel: active_anon:297 inactive_anon:41624 isolated_anon:0 active_file:58423 inactive_file:198257 isolated_file:32 unevictable:4095 dirty:5895 writeback:3507 slab_reclaimable:17419 slab_unreclaimable:5937 mapped:36303 shmem:1937 pagetables:1406 sec_pagetables:0 bounce:0 kernel_misc_reclaimable:0 free:634526 free_pcp:24 free_cma:128638 Aug 27 14:51:15 volumio kernel: Node 0 active_anon:1188kB inactive_anon:166496kB active_file:233692kB inactive_file:793028kB unevictable:16380kB isolated(anon):0kB isolated(file):128kB mapped:145212kB dirty:23580kB writeback:14028kB shmem:7748kB writeback_tmp:0kB kernel_stack:2552kB pagetables:5624kB sec_pagetables:0kB all_unreclaimable? no Aug 27 14:51:15 volumio kernel: DMA free:550632kB boost:24576kB min:40960kB low:45056kB high:49152kB reserved_highatomic:0KB active_anon:0kB inactive_anon:0kB active_file:3672kB inactive_file:644kB unevictable:0kB writepending:0kB present:786432kB managed:662956kB mlocked:0kB bounce:0kB free_pcp:96kB local_pcp:0kB free_cma:514552kB Aug 27 14:51:15 volumio kernel: lowmem_reserve[]: 0 0 3136 3136 Aug 27 14:51:15 volumio kernel: DMA: 608*4kB (UMEC) 513*8kB (UMEC) 338*16kB (UMEC) 206*32kB (UMEC) 137*64kB (UMEC) 78*128kB (UMEC) 33*256kB (UMEC) 9*512kB (UMC) 5*1024kB (C) 2*2048kB (C) 120*4096kB (C) = 551080kB Aug 27 14:51:15 volumio kernel: 260158 total pagecache pages Aug 27 14:51:15 volumio kernel: 0 pages in swap cache Aug 27 14:51:15 volumio kernel: Free swap = 0kB Aug 27 14:51:15 volumio kernel: Total swap = 0kB Aug 27 14:51:15 volumio kernel: 999424 pages RAM Aug 27 14:51:15 volumio kernel: 802816 pages HighMem/MovableOnly Aug 27 14:51:15 volumio kernel: 30869 pages reserved Aug 27 14:51:15 volumio kernel: 131072 pages cma reserved Aug 27 14:51:15 volumio kernel: Tasks state (memory values in pages): Aug 27 14:51:15 volumio kernel: [ pid ] uid tgid total_vm rss rss_anon rss_file rss_shmem pgtables_bytes swapents oom_score_adj name Aug 27 14:51:15 volumio kernel: [ 459] 0 459 7606 2256 160 1520 576 61440 0 -250 systemd-journal Aug 27 14:51:15 volumio kernel: [ 484] 0 484 5957 1152 192 960 0 45056 0 -1000 systemd-udevd Aug 27 14:51:15 volumio kernel: [ 670] 0 670 1959 1130 765 365 0 36864 0 0 haveged Aug 27 14:51:15 volumio kernel: [ 672] 108 672 1731 823 64 759 0 40960 0 0 rpcbind Aug 27 14:51:15 volumio kernel: [ 703] 100 703 602 478 64 414 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 704] 0 704 564 429 32 397 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 705] 100 705 560 279 32 247 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 706] 100 706 560 246 32 214 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 768] 103 768 1585 827 64 763 0 45056 0 0 avahi-daemon Aug 27 14:51:15 volumio kernel: [ 770] 0 770 2739 1344 96 1248 0 45056 0 0 bluetoothd Aug 27 14:51:15 volumio kernel: [ 772] 101 772 1857 918 96 822 0 40960 0 -900 dbus-daemon Aug 27 14:51:15 volumio kernel: [ 795] 0 795 7704 1637 160 1477 0 65536 0 0 systemd-logind Aug 27 14:51:15 volumio kernel: [ 796] 103 796 1545 362 33 329 0 36864 0 0 avahi-daemon Aug 27 14:51:15 volumio kernel: [ 797] 65534 797 1250 616 32 584 0 36864 0 0 thd Aug 27 14:51:15 volumio kernel: [ 801] 1000 801 11771 2483 224 2259 0 77824 0 0 volumio-remote- Aug 27 14:51:15 volumio kernel: [ 819] 100 819 638 497 96 401 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 820] 0 820 643 510 64 446 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 821] 100 821 560 263 32 231 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 822] 100 822 560 222 32 190 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 994] 0 994 3275 921 128 793 0 49152 0 0 wpa_supplicant Aug 27 14:51:15 volumio kernel: [ 996] 0 996 10966 1029 96 933 0 86016 0 0 bluealsa Aug 27 14:51:15 volumio kernel: [ 1034] 104 1034 37337 2156 448 1708 0 143360 0 0 shairport-sync Aug 27 14:51:15 volumio kernel: [ 1060] 100 1060 643 265 34 231 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 1064] 0 1064 1409 662 32 630 0 32768 0 0 bash Aug 27 14:51:15 volumio kernel: [ 1066] 0 1066 930 486 32 454 0 28672 0 0 ip Aug 27 14:51:15 volumio kernel: [ 1067] 0 1067 1393 461 32 429 0 36864 0 0 sed Aug 27 14:51:15 volumio kernel: [ 1068] 0 1068 1409 414 33 381 0 32768 0 0 bash Aug 27 14:51:15 volumio kernel: [ 1069] 106 1069 4363 4062 2454 1608 0 53248 0 0 ntpd Aug 27 14:51:15 volumio kernel: [ 1083] 0 1083 520 347 0 347 0 28672 0 0 ifplugd Aug 27 14:51:15 volumio kernel: [ 1096] 100 1096 564 292 32 260 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 1109] 0 1109 3391 1929 160 1769 0 53248 0 -1000 sshd Aug 27 14:51:15 volumio kernel: [ 1147] 0 1147 16313 3161 416 2675 70 94208 0 0 nmbd Aug 27 14:51:15 volumio kernel: [ 1153] 0 1153 16239 1050 406 644 0 90112 0 0 nmbd Aug 27 14:51:15 volumio kernel: [ 1198] 0 1198 1115 428 0 428 0 32768 0 0 agetty Aug 27 14:51:15 volumio kernel: [ 1240] 0 1240 17917 3686 480 3161 45 114688 0 0 winbindd Aug 27 14:51:15 volumio kernel: [ 1275] 100 1275 564 341 32 309 0 28672 0 0 dhcpcd Aug 27 14:51:15 volumio kernel: [ 1299] 0 1299 17977 3220 486 2691 43 114688 0 0 winbindd Aug 27 14:51:15 volumio kernel: [ 1314] 0 1314 19146 4164 512 3458 194 118784 0 0 smbd Aug 27 14:51:15 volumio kernel: [ 1326] 0 1326 18753 2595 503 2092 0 106496 0 0 smbd-notifyd Aug 27 14:51:15 volumio kernel: [ 1327] 0 1327 18753 1255 503 752 0 106496 0 0 cleanupd Aug 27 14:51:15 volumio kernel: [ 1338] 0 1338 17917 2593 488 2105 0 114688 0 0 winbindd Aug 27 14:51:15 volumio kernel: [ 1449] 0 1449 159116 3113 825 2288 0 143360 0 0 volumio5-onboar Aug 27 14:51:15 volumio kernel: [ 4278] 0 4278 3669 2258 256 2002 0 53248 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4306] 1000 4306 3734 1590 307 1283 0 53248 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4307] 1000 4307 550 452 32 420 0 28672 0 0 sftp-server Aug 27 14:51:15 volumio kernel: [ 4419] 0 4419 3669 2275 256 2019 0 53248 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4420] 0 4420 3669 2227 256 1971 0 53248 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4439] 1000 4439 4869 2697 1439 1258 0 61440 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4442] 1000 4442 4547 2391 1108 1283 0 61440 0 0 sshd Aug 27 14:51:15 volumio kernel: [ 4443] 1000 4443 584 454 64 390 0 32768 0 0 sftp-server Aug 27 14:51:15 volumio kernel: [ 4444] 1000 4444 584 454 64 390 0 24576 0 0 sftp-server Aug 27 14:51:15 volumio kernel: [ 4739] 1000 4739 64656 27220 15003 12217 0 966656 0 0 node Aug 27 14:51:15 volumio kernel: [ 4771] 1000 4771 1400 647 32 615 0 36864 0 0 network_monitor Aug 27 14:51:15 volumio kernel: [ 4797] 1000 4797 31902 8411 933 7478 0 229376 0 0 node Aug 27 14:51:15 volumio kernel: [ 4807] 1000 4807 32999 11377 2294 9083 0 311296 0 0 node Aug 27 14:51:15 volumio kernel: [ 4808] 1000 4808 32981 11371 2279 9092 0 299008 0 0 node Aug 27 14:51:15 volumio kernel: [ 4809] 1000 4809 32996 11449 2336 9113 0 299008 0 0 node Aug 27 14:51:15 volumio kernel: [ 4885] 102 4885 119475 23732 6620 17112 0 647168 0 0 mpd Aug 27 14:51:15 volumio kernel: [ 5002] 1000 5002 1115 365 0 365 0 32768 0 0 sleep Aug 27 14:51:15 volumio kernel: oom-kill:constraint=CONSTRAINT_NONE,nodemask=(null),cpuset=/,mems_allowed=0,global_oom,task_memcg=/,task=node,pid=4739,uid=1000 Aug 27 14:51:15 volumio kernel: Out of memory: Killed process 4739 (node) total-vm:258624kB, anon-rss:60012kB, file-rss:48868kB, shmem-rss:0kB, UID:1000 pgtables:944kB oom_score_adj:0 Aug 27 14:51:15 volumio volumio-remote-updater[801]: [2026-08-27 14:51:15] [error] handle_read_frame error: asio.system:104 (Connection reset by peer) Aug 27 14:51:15 volumio volumio-remote-updater[801]: [2026-08-27 14:51:15] [disconnect] Disconnect close local:[1006,Connection reset by peer] remote:[1006] Aug 27 14:51:15 volumio volumio5-onboarding[1449]: time=2026-08-27T14:51:15.591+09:00 level=ERROR msg="failed reading message" error="websocket: close 1006 (abnormal closure): unexpected EOF" Aug 27 14:51:15 volumio systemd[1]: volumio.service: Main process exited, code=killed, status=9/KILL Aug 27 14:51:15 volumio systemd[1]: volumio.service: Failed with result 'signal'. Aug 27 14:51:15 volumio systemd[1]: volumio.service: Consumed 19.592s CPU time. Aug 27 14:51:15 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Aug 27 14:51:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Aug 27 14:51:15 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Aug 27 14:51:15 volumio systemd[1]: volumio.service: Consumed 19.592s CPU time. Aug 27 14:51:15 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Aug 27 14:51:16 volumio volumio5-onboarding[1449]: time=2026-08-27T14:51:15.619+09:00 level=WARN msg="reconnection attempt failed" error="dial tcp 127.0.0.1:3000: connect: connection refused" Aug 27 14:51:16 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Aug 27 14:51:17 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 131. Aug 27 14:51:17 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:51:17 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Aug 27 14:51:17 volumio upmpdcli[5042]: Could not open config: /tmp/upmpdcli.conf Aug 27 14:51:17 volumio systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Aug 27 14:51:17 volumio systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Aug 27 14:51:17 volumio volumio[5026]: info: ------------------------------------------- Aug 27 14:51:17 volumio volumio[5026]: info: ----- Volumio3 ---- Aug 27 14:51:17 volumio volumio[5026]: info: ------------------------------------------- Aug 27 14:51:17 volumio volumio[5026]: info: ----- System startup ---- Aug 27 14:51:17 volumio volumio[5026]: info: ------------------------------------------- Aug 27 14:51:18 volumio volumio[5026]: info: MYVOLUMIO Environment detected Aug 27 14:51:18 volumio volumio[5026]: info: Plugin folders cleanup Aug 27 14:51:18 volumio volumio[5026]: info: Scanning into folder /volumio/app/plugins/ Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category audio_interface Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category miscellanea Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category music_service Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category plugins.json Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category system_controller Aug 27 14:51:18 volumio volumio[5026]: info: Scanning category user_interface Aug 27 14:51:18 volumio volumio[5026]: info: Scanning into folder /data/plugins/ Aug 27 14:51:18 volumio volumio[5026]: info: Plugin folders cleanup completed Aug 27 14:51:18 volumio volumio[5026]: info: ------------------------------------------- Aug 27 14:51:18 volumio volumio[5026]: info: ----- Core plugins startup ---- Aug 27 14:51:18 volumio volumio[5026]: info: ------------------------------------------- Aug 27 14:51:18 volumio volumio[5026]: info: Loading plugins from folder /volumio/app/plugins/ Aug 27 14:51:18 volumio volumio[5026]: info: Adding plugin upnp to MyMusic Plugins Aug 27 14:51:18 volumio volumio[5026]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 27 14:51:18 volumio volumio[5026]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 27 14:51:18 volumio volumio[5026]: info: Loading plugins from folder /data/plugins/ Aug 27 14:51:18 volumio volumio[5026]: info: Loading plugin "system"... Aug 27 14:51:18 volumio volumio[5026]: info: Loading plugin "appearance"... Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "network"... Aug 27 14:51:19 volumio volumio[5026]: info: Refreshing Cached IP Addresses Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "services"... Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "volumio5onboarding"... Aug 27 14:51:19 volumio sudo[5056]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 27 14:51:19 volumio sudo[5056]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:19 volumio sudo[5056]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "alsa_controller"... Aug 27 14:51:19 volumio sudo[5058]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 27 14:51:19 volumio sudo[5058]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:19 volumio sudo[5058]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:19 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "wizard"... Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "networkfs"... Aug 27 14:51:19 volumio volumio[5026]: info: Starting Udev Watcher for removable devices Aug 27 14:51:19 volumio volumio[5026]: info: Ignoring mount for partition: boot Aug 27 14:51:19 volumio volumio[5026]: info: Ignoring mount for partition: volumio Aug 27 14:51:19 volumio volumio[5026]: info: Ignoring mount for partition: volumio_data Aug 27 14:51:19 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "volumio_command_line_client"... Aug 27 14:51:19 volumio volumio[5026]: info: Plugin upnp is not enabled Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "my_music"... Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "mpd"... Aug 27 14:51:19 volumio volumio[5026]: info: Plugin upnp_browser is not enabled Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "alarm-clock"... Aug 27 14:51:19 volumio volumio[5026]: info: Plugin airplay_emulation is not enabled Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "last_100"... Aug 27 14:51:19 volumio volumio[5026]: info: Loading plugin "webradio"... Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "i2s_dacs"... Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "volumiodiscovery"... Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** For more information see Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:51:20 volumio volumio[5026]: *** WARNING *** For more information see Aug 27 14:51:20 volumio node[5026]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 27 14:51:20 volumio node[5026]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:51:20 volumio node[5026]: *** WARNING *** For more information see Aug 27 14:51:20 volumio node[5026]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 27 14:51:20 volumio node[5026]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 27 14:51:20 volumio node[5026]: *** WARNING *** For more information see Aug 27 14:51:20 volumio volumio[5026]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 27 14:51:20 volumio volumio[5026]: info: Discovery: Started advertising with name: Volumio Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "outputs"... Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "albumart"... Aug 27 14:51:20 volumio volumio[5026]: info: Plugin example_plugin is not enabled Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "inputs"... Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "updater_comm"... Aug 27 14:51:20 volumio volumio[5026]: info: Plugin mpdemulation is not enabled Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "rest_api"... Aug 27 14:51:20 volumio volumio[5026]: info: Loading plugin "websocket"... Aug 27 14:51:20 volumio volumio[5026]: info: Starting Socket.io Server version 1.7.4 Aug 27 14:51:20 volumio volumio[5026]: info: Loading i18n strings for locale ko Aug 27 14:51:20 volumio volumio[5026]: Updating browse sources language Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::initPlayerControls Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 27 14:51:20 volumio volumio[5026]: Express server listening on port 3000 Aug 27 14:51:20 volumio volumio[5026]: [Metrics] WebUI: 3s 197.27ms Aug 27 14:51:20 volumio volumio-remote-updater[801]: [2026-08-27 14:51:20] [connect] Successful connection Aug 27 14:51:20 volumio volumio[5026]: info: CoreStateMachine::resetVolumioState Aug 27 14:51:20 volumio volumio[5026]: info: CoreStateMachine::getcurrentVolume Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::volumioRetrievevolume Aug 27 14:51:20 volumio volumio[5026]: info: CoreStateMachine::pushState Aug 27 14:51:20 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:20 volumio volumio[5026]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:20 volumio volumio[5086]: Forking 3 albumart workers Aug 27 14:51:20 volumio volumio[5026]: info: Volumio Network Manager: Network status updated: 1 Aug 27 14:51:20 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 1 Aug 27 14:51:21 volumio volumio[5026]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.255.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:21 volumio volumio[5026]: info: Reloading queue from file Aug 27 14:51:21 volumio volumio[5026]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.255.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Aug 27 14:51:21 volumio volumio[5026]: info: CoreStateMachine::setRepeat true single undefined Aug 27 14:51:21 volumio volumio[5026]: info: CoreStateMachine::pushState Aug 27 14:51:21 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:21 volumio volumio[5026]: info: CoreStateMachine::setRandom true Aug 27 14:51:21 volumio volumio[5026]: info: CoreStateMachine::pushState Aug 27 14:51:21 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::volumioPushState Aug 27 14:51:21 volumio volumio[5026]: info: Setting Device type: Raspberry PI Aug 27 14:51:21 volumio volumio-remote-updater[801]: [2026-08-27 14:51:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1787809880 101 Aug 27 14:51:21 volumio volumio[5026]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 3 Aug 27 14:51:21 volumio volumio[5026]: info: Completed loading Core Plugins Aug 27 14:51:21 volumio volumio[5026]: info: Preparing to generate the ALSA configuration file Aug 27 14:51:21 volumio volumio[5026]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 27 14:51:21 volumio volumio[5026]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Aug 27 14:51:21 volumio volumio[5026]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 27 14:51:21 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 27 14:51:21 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 27 14:51:21 volumio volumio[5026]: info: Asound.conf file unchanged, so no further update is needed Aug 27 14:51:21 volumio volumio[5026]: info: Output device has changed, restarting MPD Aug 27 14:51:21 volumio volumio[5026]: info: ___________ START PLUGINS ___________ Aug 27 14:51:21 volumio sudo[5137]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:51:21 volumio sudo[5137]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:21 volumio sudo[5137]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:21 volumio volumio[5026]: info: ControllerMpd::onStart: Initializing MPD Aug 27 14:51:21 volumio volumio[5026]: info: Creating MPD Configuration file Aug 27 14:51:21 volumio sudo[5139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:51:21 volumio sudo[5139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 14:51:21 volumio volumio[5026]: info: [1787809881478] CoreMusicLibrary::Adding element Last_100 Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:51:21 volumio sudo[5142]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Aug 27 14:51:21 volumio sudo[5142]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 27 14:51:21 volumio volumio[5026]: info: [1787809881505] CoreMusicLibrary::Adding element Webradio Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:51:21 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 27 14:51:21 volumio sudo[5147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 27 14:51:21 volumio sudo[5147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:21 volumio sudo[5146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 27 14:51:21 volumio sudo[5146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:51:21 volumio sudo[5142]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:21 volumio volumio[5026]: info: Initializing BBC Radios Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 27 14:51:21 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:21 volumio volumio[5026]: info: Volumio Calling Home Aug 27 14:51:21 volumio volumio[5100]: Starting albumart workers Aug 27 14:51:22 volumio volumio[5102]: Starting albumart workers Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 27 14:51:22 volumio volumio[5026]: info: Discovery: adding f8cba9ce-b227-4dfa-99d4-964e5127c5ee Aug 27 14:51:22 volumio volumio[5026]: info: Discovery: Found device Volumio Aug 27 14:51:22 volumio volumio[5101]: Starting albumart workers Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::volumioGetState Aug 27 14:51:22 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:22 volumio volumio[5026]: info: MPD Permissions set Aug 27 14:51:22 volumio volumio[5026]: info: Discovery: this is already registered, f8cba9ce-b227-4dfa-99d4-964e5127c5ee Aug 27 14:51:22 volumio volumio[5026]: info: Discovery: Found device Volumio Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::volumioGetState Aug 27 14:51:22 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 4 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio sudo[5147]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:22 volumio volumio[5026]: info: Volumio called home Aug 27 14:51:22 volumio volumio[5026]: info: MPD Permissions set Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: info: CoreCommandRouter::volumioGetState Aug 27 14:51:22 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:22 volumio volumio[5026]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 5 Aug 27 14:51:23 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 27 14:51:23 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 27 14:51:23 volumio systemd[1]: mpd.service: Consumed 3.185s CPU time. Aug 27 14:51:23 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 27 14:51:23 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 27 14:51:23 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 27 14:51:23 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 27 14:51:23 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 27 14:51:23 volumio volumio[5026]: verbose: New Socket.io Connection to 192.168.0.3 from 192.168.255.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/151.0.0.0 Safari/537.36 Edg/151.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Aug 27 14:51:23 volumio sudo[5199]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 27 14:51:23 volumio sudo[5199]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 27 14:51:23 volumio sudo[5199]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:23 volumio volumio[5026]: info: CoreCommandRouter::volumioGetState Aug 27 14:51:23 volumio volumio[5026]: info: CorePlayQueue::getTrack 0 Aug 27 14:51:23 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 27 14:51:23 volumio volumio[5026]: info: Listing playlists Aug 27 14:51:23 volumio volumio[5026]: info: CoreCommandRouter::volumioGetVisibleSources Aug 27 14:51:23 volumio volumio[5026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 27 14:51:23 volumio volumio[5026]: info: CoreCommandRouter::volumioGetQueue Aug 27 14:51:23 volumio volumio[5026]: info: CoreStateMachine::getQueue Aug 27 14:51:23 volumio volumio[5026]: info: CorePlayQueue::getQueue Aug 27 14:51:25 volumio volumio[5026]: info: Preload queue cleared Aug 27 14:51:25 volumio volumio[5026]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 27 14:51:25 volumio volumio[5026]: info: CoreStateMachine::ClearQueue Aug 27 14:51:25 volumio volumio[5026]: info: CoreStateMachine::stop Aug 27 14:51:25 volumio volumio[5026]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 27 14:51:25 volumio volumio[5026]: info: CorePlayQueue::clearPlayQueue Aug 27 14:51:25 volumio volumio[5026]: info: CorePlayQueue::saveQueue Aug 27 14:51:25 volumio volumio[5026]: info: CoreCommandRouter::volumioPushQueue Aug 27 14:51:25 volumio volumio[5026]: info: CoreStateMachine::addQueueItems Aug 27 14:51:25 volumio volumio[5026]: info: CorePlayQueue::addQueueItems Aug 27 14:51:25 volumio volumio[5026]: info: Preload queue cleared Aug 27 14:51:25 volumio volumio[5026]: info: Adding Item to queue: music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac Aug 27 14:51:25 volumio volumio[5026]: info: Exploding uri music-library/INTERNAL/Enya 엔야[전집.리마스터링.Best]앨범/엔야 - Trains and Winter Rains.flac in service mpd Aug 27 14:51:25 volumio volumio[5026]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 14:51:25 volumio volumio[5026]: TypeError: Cannot read properties of undefined (reading 'then') Aug 27 14:51:25 volumio volumio[5026]: at ControllerMpd.scanFolder (/volumio/app/plugins/music_service/mpd/index.js:2541:19) Aug 27 14:51:25 volumio volumio[5026]: at ControllerMpd.explodeUri (/volumio/app/plugins/music_service/mpd/index.js:2339:21) Aug 27 14:51:25 volumio volumio[5026]: at CoreCommandRouter.explodeUriFromService (/volumio/app/index.js:1384:16) Aug 27 14:51:25 volumio volumio[5026]: at CorePlayQueue.explodeUriFromCache (/volumio/app/playqueue.js:123:24) Aug 27 14:51:25 volumio volumio[5026]: at CorePlayQueue.explodeUri (/volumio/app/playqueue.js:181:19) Aug 27 14:51:25 volumio volumio[5026]: at CorePlayQueue.addQueueItems (/volumio/app/playqueue.js:223:30) Aug 27 14:51:25 volumio volumio[5026]: at CoreStateMachine.addQueueItems (/volumio/app/statemachine.js:251:25) Aug 27 14:51:25 volumio volumio[5026]: at CoreCommandRouter.replaceAndPlay (/volumio/app/index.js:511:25) Aug 27 14:51:25 volumio volumio[5026]: at CoreCommandRouter.playItemsList (/volumio/app/index.js:484:17) Aug 27 14:51:25 volumio volumio[5026]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:142:33) Aug 27 14:51:25 volumio volumio[5026]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 27 14:51:25 volumio mpd[5201]: 2026-08-27T14:51:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 27 14:51:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 27 14:51:25 volumio sudo[5146]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:25 volumio sudo[5139]: pam_unix(sudo:session): session closed for user root Aug 27 14:51:25 volumio sudo[5218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-27 14:50' Aug 27 14:51:25 volumio sudo[5218]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"