I was able to catch the dmesg
[ 49.650131] i2c i2c-1: [aml_i2c_xfer] error ret = -5 (-EIO) token 1, master_no(1) 100K addr 0x77
[ 49.659020] i2c i2c-1: [aml_i2c_xfer] error ret = -5 (-EIO) token 1, master_no(1) 100K addr 0x77
[ 49.667982] i2c i2c-1: [aml_i2c_xfer] error ret = -5 (-EIO) token 1, master_no(1) 100K addr 0x77
[ 156.544307] _hcd_isoc_complete:Uknown urb status -2
[ 304.568678] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983200 status=-104
[ 304.575219] _hcd_isoc_complete:Uknown urb status -2
[ 304.575240] _hcd_isoc_complete:Uknown urb status -2
[ 309.344706] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983000 status=-104
[ 309.351411] _hcd_isoc_complete:Uknown urb status -2
[ 315.357188] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983100 status=-104
[ 315.364088] _hcd_isoc_complete:Uknown urb status -2
[ 319.800116] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 319.807306] _hcd_isoc_complete:Uknown urb status -2
[ 323.089087] [aml_sdhc_data_thread] SDHC_ESTA=0x0
[ 323.392955] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 323.398889] _hcd_isoc_complete:Uknown urb status -2
[ 323.398905] _hcd_isoc_complete:Uknown urb status -2
[ 327.556436] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 327.564073] _hcd_isoc_complete:Uknown urb status -2
[ 330.113873] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983200 status=-104
[ 330.121720] _hcd_isoc_complete:Uknown urb status -2
[ 331.575877] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983200 status=-104
[ 331.584010] _hcd_isoc_complete:Uknown urb status -2
[ 337.570615] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983300 status=-104
[ 337.578828] _hcd_isoc_complete:Uknown urb status -2
[ 341.159559] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 341.167998] _hcd_isoc_complete:Uknown urb status -2
[ 344.310022] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983200 status=-104
[ 344.318762] _hcd_isoc_complete:Uknown urb status -2
[ 350.589448] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983200 status=-104
[ 350.598244] _hcd_isoc_complete:Uknown urb status -2
[ 355.558647] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 355.567628] _hcd_isoc_complete:Uknown urb status -2
[ 359.290764] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983b00 status=-104
[ 359.299953] _hcd_isoc_complete:Uknown urb status -2
[ 365.118953] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
[ 365.128363] _hcd_isoc_complete:Uknown urb status -2
[ 369.527972] update invoked oom-killer: gfp_mask=0x200da, order=0, oom_score_adj=0
[ 369.532953] update cpuset=/ mems_allowed=0
[ 369.538190] CPU: 3 PID: 1187 Comm: update Not tainted 3.10.104 #10
[ 369.543669] [<c0014eb4>] (unwind_backtrace+0x0/0xec) from [<c0011ea8>] (show_stack+0x10/0x14)
[ 369.552243] [<c0011ea8>] (show_stack+0x10/0x14) from [<c066a78c>] (dump_header+0x80/0x1bc)
[ 369.560714] [<c066a78c>] (dump_header+0x80/0x1bc) from [<c00b068c>] (oom_kill_process+0x74/0x3a4)
[ 369.569759] [<c00b068c>] (oom_kill_process+0x74/0x3a4) from [<c00b0e2c>] (out_of_memory+0x2a8/0x2fc)
[ 369.579066] [<c00b0e2c>] (out_of_memory+0x2a8/0x2fc) from [<c00b4f44>] (__alloc_pages_nodemask+0x858/0xa18)
[ 369.588932] [<c00b4f44>] (__alloc_pages_nodemask+0x858/0xa18) from [<c00cdc10>] (handle_pte_fault+0x180/0x910)
[ 369.599080] [<c00cdc10>] (handle_pte_fault+0x180/0x910) from [<c00ce4d4>] (handle_mm_fault+0x134/0x1e0)
[ 369.608651] [<c00ce4d4>] (handle_mm_fault+0x134/0x1e0) from [<c0019e0c>] (do_page_fault+0x118/0x3c8)
[ 369.617900] [<c0019e0c>] (do_page_fault+0x118/0x3c8) from [<c0008328>] (do_DataAbort+0x38/0x98)
[ 369.626792] [<c0008328>] (do_DataAbort+0x38/0x98) from [<c000dd34>] (__dabt_usr+0x34/0x40)
[ 369.635166] Exception stack(0xda9dbfb0 to 0xda9dbff8)
[ 369.640399] bfa0: 91ff2ff8 b3c87774 00001001 91ff2ff0
[ 369.648720] bfc0: ae100010 00000015 00000010 91ff2ff0 fff00000 000f4000 91f00000 91f00000
[ 369.657075] bfe0: 91ff3000 aea661d8 b3bb8ed4 b3bb8b5c 800d0010 ffffffff
[ 369.663845] Mem-info:
[ 369.667634] Normal per-cpu:
[ 369.671404] CPU 0: hi: 186, btch: 31 usd: 99
[ 369.675313] CPU 1: hi: 186, btch: 31 usd: 73
[ 369.679472] CPU 2: hi: 186, btch: 31 usd: 103
[ 369.684483] CPU 3: hi: 186, btch: 31 usd: 0
[ 369.689386] HighMem per-cpu:
[ 369.693287] CPU 0: hi: 90, btch: 15 usd: 39
[ 369.697360] CPU 1: hi: 90, btch: 15 usd: 14
[ 369.702318] CPU 2: hi: 90, btch: 15 usd: 15
[ 369.707317] CPU 3: hi: 90, btch: 15 usd: 49
[ 369.712226] active_anon:166047 inactive_anon:1133 isolated_anon:0
active_file:1396 inactive_file:6667 isolated_file:0
unevictable:0 dirty:0 writeback:0 unstable:0
free:2460 slab_reclaimable:2067 slab_unreclaimable:4842
mapped:1769 shmem:1484 pagetables:1038 bounce:0
free_cma:1232
[ 369.763625] Normal free:9676kB min:2980kB low:3724kB high:4468kB active_anon:462132kB inactive_anon:156kB active_file:2976kB inactive_file:24028kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:628736kB managed:555928kB mlocked:0kB dirty:0kB writeback:0kB mapped:2276kB shmem:200kB slab_reclaimable:8268kB slab_unreclaimable:19368kB kernel_stack:1736kB pagetables:4152kB unstable:0kB bounce:0kB free_cma:4928kB writeback_tmp:0kB pages_scanned:42327 all_unreclaimable? yes
[ 369.814223] lowmem_reserve[]: 0 1704 1704
[ 369.822756] HighMem free:164kB min:212kB low:504kB high:796kB active_anon:202056kB inactive_anon:4376kB active_file:2608kB inactive_file:2640kB unevictable:0kB isolated(anon):0kB isolated(file):0kB present:218112kB managed:218112kB mlocked:0kB dirty:0kB writeback:0kB mapped:4800kB shmem:5736kB slab_reclaimable:0kB slab_unreclaimable:0kB kernel_stack:0kB pagetables:0kB unstable:0kB bounce:118984kB free_cma:0kB writeback_tmp:0kB pages_scanned:15258 all_unreclaimable? yes
[ 369.868756] lowmem_reserve[]: 0 0 0
[ 369.877233] Normal: 1543*4kB (UEC) 2*8kB (R) 1*16kB (R) 0*32kB 1*64kB (R) 0*128kB 1*256kB (R) 0*512kB 1*1024kB (R) 1*2048kB (R) 0*4096kB = 9596kB
[ 369.889788] HighMem: 5*4kB (UM) 3*8kB (UM) 2*16kB (UR) 3*32kB (UM) 0*64kB 0*128kB 0*256kB 0*512kB 0*1024kB 0*2048kB 0*4096kB = 172kB
[ 369.902010] 9638 total pagecache pages
[ 369.907382] 0 pages in swap cache
[ 369.912779] Swap cache stats: add 0, delete 0, find 0/0
[ 369.918201] Free swap = 0kB
[ 369.923629] Total swap = 0kB
[ 369.939708] 211712 pages of RAM
[ 369.945246] 4666 free pages
[ 369.950709] 5820 reserved pages
[ 369.956182] 4977 slab pages
[ 369.961666] 1044197 pages shared
[ 369.967223] 0 pages swap cached
[ 369.972756] [ pid ] uid tgid total_vm rss nr_ptes swapents oom_score_adj name
[ 369.980735] [ 197] 0 197 1869 566 6 0 0 systemd-journal
[ 369.989699] [ 216] 0 216 2602 175 6 0 -1000 systemd-udevd
[ 369.998542] [ 443] 0 443 921 121 4 0 0 rpcbind
[ 370.006902] [ 453] 103 453 972 153 5 0 0 rpc.statd
[ 370.015403] [ 467] 0 467 594 40 4 0 0 rpc.idmapd
[ 370.024028] [ 469] 0 469 1792 801 6 0 0 haveged
[ 370.032366] [ 473] 1000 473 1634 144 5 0 0 udisks-glue
[ 370.041144] [ 477] 1000 477 7443 224 10 0 0 volumio-remote-
[ 370.050259] [ 478] 0 478 586 139 4 0 0 volumiologrotat
[ 370.059414] [ 491] 0 491 776 110 4 0 0 systemd-logind
[ 370.068509] [ 508] 104 508 1159 173 5 0 0 avahi-daemon
[ 370.077410] [ 509] 110 509 181568 133884 326 0 0 mpd
[ 370.085568] [ 511] 102 511 1272 182 5 0 -900 dbus-daemon
[ 370.094474] [ 561] 65534 561 451 28 3 0 0 thd
[ 370.102861] [ 573] 0 573 354 67 3 0 0 ifplugd
[ 370.112729] [ 614] 104 614 1131 66 5 0 0 avahi-daemon
[ 370.121932] [ 642] 100 642 5030 415 12 0 0 ntpd
[ 370.130284] [ 680] 0 680 1639 181 5 0 -1000 sshd
[ 370.138624] [ 683] 0 683 4091 199 7 0 0 udisks-daemon
[ 370.147780] [ 704] 0 704 1749 78 6 0 0 udisks-daemon
[ 370.156955] [ 720] 0 720 8686 182 11 0 0 polkitd
[ 370.165610] [ 798] 0 798 4563 445 11 0 0 nmbd
[ 370.174011] [ 799] 0 799 4419 288 10 0 0 nmbd
[ 370.182406] [ 800] 0 800 5834 397 13 0 0 winbindd
[ 370.191187] [ 802] 0 802 5831 467 13 0 0 winbindd
[ 370.199973] [ 805] 0 805 422 45 3 0 0 dhcpcd
[ 370.208598] [ 810] 1000 810 50207 14206 128 0 0 node
[ 370.217073] [ 838] 0 838 919 53 4 0 0 agetty
[ 370.225745] [ 839] 0 839 452 66 4 0 0 agetty
[ 370.234424] [ 910] 0 910 7630 524 17 0 0 smbd
[ 370.242953] [ 913] 0 913 5834 411 13 0 0 winbindd
[ 370.251824] [ 914] 0 914 5834 391 12 0 0 winbindd
[ 370.260715] [ 915] 0 915 7630 450 16 0 0 smbd
[ 370.269275] [ 929] 0 929 2657 254 7 0 0 sshd
[ 370.277910] [ 979] 1000 979 1134 179 4 0 0 systemd
[ 370.286797] [ 980] 1000 980 1641 420 5 0 0 (sd-pam)
[ 370.295787] [ 982] 1000 982 2690 210 6 0 0 sshd
[ 370.304423] [ 983] 1000 983 1147 195 4 0 0 bash
[ 370.313067] [ 988] 1000 988 18111 2035 43 0 0 node
[ 370.321703] [ 997] 1000 997 28717 3686 64 0 0 node
[ 370.330366] [ 1002] 1000 1002 28795 3667 63 0 0 node
[ 370.339047] [ 1003] 1000 1003 28740 3748 65 0 0 node
[ 370.347729] [ 1086] 1000 1086 27315 249 21 0 0 upmpdcli
[ 370.356784] [ 1087] 999 1087 22739 232 19 0 0 shairport-sync
[ 370.366385] [ 1129] 1000 1129 212628 529 15 0 0 streaming-daemo
[ 370.377091] [ 1516] 0 1516 2657 254 7 0 0 sshd
[ 370.386816] [ 1541] 1000 1541 2657 194 6 0 0 sshd
[ 370.396542] [ 1542] 1000 1542 1146 91 5 0 0 bash
[ 370.406148] [ 1558] 1000 1558 2148 174 8 0 0 journalctl
[ 370.416237] [ 1795] 0 1795 586 107 3 0 0 volumiologrotat
[ 370.426789] [ 1796] 0 1796 651 190 4 0 0 ls
[ 370.436158] [ 1797] 0 1797 342 59 3 0 0 cut
[ 370.445569] Out of memory: Kill process 509 (mpd) score 651 or sacrifice child
[ 370.454451] Killed process 509 (mpd) total-vm:726272kB, anon-rss:535296kB, file-rss:236kB
[ 370.836433] _hcd_isoc_complete:Uknown urb status -2
it seems like it is indeed a memory issue (i have 1.5Gb files in my library btw)
When this starts
[ 315.364088] _hcd_isoc_complete:Uknown urb status -2
[ 319.800116] WARN::urb_dequeue:964: urb->hcpriv == NULL! urb = da983e00 status=-104
the audio start to stutters probably due to resoruces, the question is why mpd goes so high in memory consumption
So i start to understand what’s the issue here
Previously i’ve installed the following mpd
to make volumio-ytcr work better (and apparently no issues were present aside from the scanning).
So what i did was reflash the official image and start the library scan.
It turned out that after 30min the library scanned only 15 songs.
With this new mpd the library scan went so much faster (60 albums in like 5s) but it always ends up eating too much memory and crash at the end.
I’ve also noticed that if i turn off via volumio ui half-way the scan, it always restarts from zero.
Unfortunately i can say for sure that the old version would end up completing the scan properly.