[PROBLEM] Library doesn't update.

[vinnn]

Yeah doubt it's the number of tracks, mpd is capable of efficiently renerating large libraries and the Pi 3B should be fine for that amount of albums, the mpd database would be just a few MBs.

[Tim Curtis]

Remember that the Library in moOde is an array (the Library tag cache) thats loaded entirely into Browser memory. The array contains the metadata in the MPD database plus custom metadata elements that are generated by moOde.

You can see how large the array is by ls -l /var/local/www/libcache_all.json

[vinnn]

Yep but with my ~99000 track library the cache is still less than 50MB which is more of a task for the device rendering the web page in a browser rather than the Pi serving it.

[TheOldPresbyope]

Remember that the Library in moOde is an array (the Library tag cache) thats loaded entirely into Browser memory. The array contains the metadata in the MPD database plus custom metadata elements that are generated by moOde.

You can see how large the array is by ls -l /var/local/www/libcache_all.json

So looking at one moOde player with 7790 tracks according to 'mpc stats', I see the cache is ca 4.4MB. A super rough estimate of the size of OPs cache would be (190046/7790) x 4.4MB = 107MB.

I don't know it that's a little or a lot for a browser on, say, my Pixel 3a phone.

I also don't know why that would prevent the update library job from starting.

This calls for a glass of wine!

Regards,
Kent

[Tim Curtis]

lol, for sure.

[Zinck84]

Thank you.

The MPD restart is because I'm simply trying everything I can think would work. I though maybe it was an MPD issue.

If there is something I can do to check the issue on my side, I'll be glad to do so.

Tomorrow I'll try with another pi I have arround here, although it is a Raspberry 2, it can help to know if it is a problem from the NAS or simply my SD's. As with the MPD restart, just trying things.  

Thanks again.

[Tim Curtis]

Examine the MPD log. It records successful file add/update/remove and also errors.

Code:

cat /var/log/mpd/log

[Zinck84]

It returns this to me:

Code:

Dec 31 10:32 : state_file: Loading state file /var/lib/mpd/state
Dec 31 10:32 : client: [0] opened from 127.0.0.1:37828
Dec 31 10:32 : client: [0] process command "close"
Dec 31 10:32 : client: [0] command returned 4
Dec 31 10:32 : client: [0] closed
Dec 31 10:32 : client: [1] opened from 127.0.0.1:37832
Dec 31 10:32 : client: [1] process command "setvol "0""
Dec 31 10:32 : client: [1] command returned 3
Dec 31 10:32 : client: [1] closed
Dec 31 10:32 : client: [2] opened from 127.0.0.1:37836
Dec 31 10:32 : client: [2] process command "status"
Dec 31 10:32 : client: [2] command returned 0
Dec 31 10:32 : client: [2] process command "currentsong"
Dec 31 10:32 : client: [2] command returned 0
Dec 31 10:32 : client: [2] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:32 : client: [2] command returned 0
Dec 31 10:32 : client: [2] process command "close"
Dec 31 10:32 : client: [2] command returned 4
Dec 31 10:32 : client: [2] closed
Dec 31 10:32 : client: [3] opened from 127.0.0.1:37840
Dec 31 10:32 : client: [3] process command "status"
Dec 31 10:32 : client: [3] command returned 0
Dec 31 10:32 : client: [3] process command "idle"
Dec 31 10:32 : client: [3] command returned 1
Dec 31 10:32 : client: [4] opened from 127.0.0.1:37844
Dec 31 10:32 : client: [4] process command "status"
Dec 31 10:32 : client: [4] command returned 0
Dec 31 10:32 : client: [4] process command "currentsong"
Dec 31 10:32 : client: [4] command returned 0
Dec 31 10:32 : client: [4] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:32 : client: [4] command returned 0
Dec 31 10:32 : client: [4] process command "close"
Dec 31 10:32 : client: [4] command returned 4
Dec 31 10:32 : client: [4] closed
Dec 31 10:32 : client: [5] opened from 127.0.0.1:37848
Dec 31 10:32 : client: [5] process command "status"
Dec 31 10:32 : client: [5] command returned 0
Dec 31 10:32 : client: [5] process command "idle"
Dec 31 10:32 : client: [5] command returned 1
Dec 31 10:32 : client: [6] opened from 127.0.0.1:37852
Dec 31 10:32 : client: [6] process command "status"
Dec 31 10:32 : client: [6] command returned 0
Dec 31 10:32 : client: [6] process command "currentsong"
Dec 31 10:32 : client: [6] command returned 0
Dec 31 10:32 : client: [6] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:32 : client: [6] command returned 0
Dec 31 10:32 : client: [6] process command "close"
Dec 31 10:32 : client: [6] command returned 4
Dec 31 10:32 : client: [6] closed
Dec 31 10:32 : client: [7] opened from 127.0.0.1:37856
Dec 31 10:32 : client: [7] process command "status"
Dec 31 10:32 : client: [7] command returned 0
Dec 31 10:32 : client: [7] process command "idle"
Dec 31 10:32 : client: [7] command returned 1
Dec 31 10:32 : client: [8] opened from 127.0.0.1:37860
Dec 31 10:32 : client: [8] process command "status"
Dec 31 10:32 : client: [8] command returned 0
Dec 31 10:32 : client: [8] process command "currentsong"
Dec 31 10:32 : client: [8] command returned 0
Dec 31 10:32 : client: [8] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:32 : client: [8] command returned 0
Dec 31 10:32 : client: [8] process command "close"
Dec 31 10:32 : client: [8] command returned 4
Dec 31 10:32 : client: [8] closed
Dec 31 10:32 : client: [9] opened from 127.0.0.1:37864
Dec 31 10:32 : client: [9] process command "status"
Dec 31 10:32 : client: [9] command returned 0
Dec 31 10:32 : client: [9] process command "idle"
Dec 31 10:32 : client: [9] command returned 1
Dec 31 10:32 : client: [10] opened from 127.0.0.1:37868
Dec 31 10:32 : client: [10] closed
Dec 31 10:32 : client: [11] opened from 127.0.0.1:37872
Dec 31 10:32 : client: [11] process command "status"
Dec 31 10:32 : client: [11] command returned 0
Dec 31 10:32 : client: [11] process command "currentsong"
Dec 31 10:32 : client: [11] command returned 0
Dec 31 10:32 : client: [11] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:32 : client: [11] command returned 0
Dec 31 10:32 : client: [11] process command "close"
Dec 31 10:32 : client: [11] command returned 4
Dec 31 10:32 : client: [11] closed
Dec 31 10:33 : client: [12] opened from 127.0.0.1:37876
Dec 31 10:33 : client: [12] process command "status"
Dec 31 10:33 : client: [12] command returned 0
Dec 31 10:33 : client: [12] process command "idle"
Dec 31 10:33 : client: [12] command returned 1
Dec 31 10:33 : client: [13] opened from 127.0.0.1:37880
Dec 31 10:33 : client: [13] process command "lsinfo"
Dec 31 10:33 : client: [13] command returned 0
Dec 31 10:33 : client: [13] process command "search base "NAS""
Dec 31 10:33 : client: [13] command returned 0
Dec 31 10:33 : client: [14] opened from 127.0.0.1:37884
Dec 31 10:33 : client: [13] closed
Dec 31 10:33 : client: [9] closed
Dec 31 10:33 : client: [5] closed
Dec 31 10:33 : client: [3] closed
Dec 31 10:33 : client: [7] closed
Dec 31 10:33 : client: [12] closed
Dec 31 10:33 : client: [15] opened from 127.0.0.1:37888
Dec 31 10:33 : client: [14] closed
Dec 31 10:33 : client: [16] opened from 127.0.0.1:37892
Dec 31 10:33 : client: [15] closed
Dec 31 10:33 : client: [16] closed
Dec 31 10:33 : client: [17] opened from 127.0.0.1:37896
Dec 31 10:33 : client: [17] process command "status"
Dec 31 10:33 : client: [17] command returned 0
Dec 31 10:33 : client: [17] process command "currentsong"
Dec 31 10:33 : client: [17] command returned 0
Dec 31 10:33 : client: [17] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:33 : client: [17] command returned 0
Dec 31 10:33 : client: [17] process command "close"
Dec 31 10:33 : client: [17] command returned 4
Dec 31 10:33 : client: [17] closed
Dec 31 10:33 : client: [18] opened from 127.0.0.1:37900
Dec 31 10:33 : client: [18] process command "status"
Dec 31 10:33 : client: [18] command returned 0
Dec 31 10:33 : client: [18] process command "idle"
Dec 31 10:33 : client: [18] command returned 1
Dec 31 10:33 : client: [19] opened from 127.0.0.1:37904
Dec 31 10:33 : client: [19] process command "playlistinfo"
Dec 31 10:33 : client: [19] command returned 0
Dec 31 10:33 : client: [19] process command "close"
Dec 31 10:33 : client: [19] command returned 4
Dec 31 10:33 : client: [19] closed
Dec 31 10:36 : client: [20] opened from 127.0.0.1:37910
Dec 31 10:36 : client: [20] process command "status"
Dec 31 10:36 : client: [20] command returned 0
Dec 31 10:36 : client: [20] process command "currentsong"
Dec 31 10:36 : client: [20] command returned 0
Dec 31 10:36 : client: [20] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:36 : client: [20] command returned 0
Dec 31 10:36 : client: [20] process command "close"
Dec 31 10:36 : client: [20] command returned 4
Dec 31 10:36 : client: [20] closed
Dec 31 10:36 : client: [21] opened from 127.0.0.1:37914
Dec 31 10:36 : client: [21] process command "status"
Dec 31 10:36 : client: [21] command returned 0
Dec 31 10:36 : client: [21] process command "idle"
Dec 31 10:36 : client: [21] command returned 1
Dec 31 10:36 : client: [22] opened from 127.0.0.1:37918
Dec 31 10:36 : client: [22] process command "setvol "0""
Dec 31 10:36 : client: [22] command returned 3
Dec 31 10:36 : client: [18] process command "status"
Dec 31 10:36 : client: [18] command returned 0
Dec 31 10:36 : client: [21] process command "status"
Dec 31 10:36 : client: [21] command returned 0
Dec 31 10:36 : client: [22] closed
Dec 31 10:36 : client: [18] process command "currentsong"
Dec 31 10:36 : client: [18] command returned 0
Dec 31 10:36 : client: [21] process command "currentsong"
Dec 31 10:36 : client: [21] command returned 0
Dec 31 10:36 : client: [18] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:36 : client: [18] command returned 0
Dec 31 10:36 : client: [18] process command "close"
Dec 31 10:36 : client: [18] command returned 4
Dec 31 10:36 : client: [18] closed
Dec 31 10:36 : client: [21] process command "lsinfo "NAS/NAS/Musica/OST Juegos/EEUU/Kyle Gabler/World of Goo/13 Cog in the Machine.flac""
Dec 31 10:36 : client: [21] command returned 0
Dec 31 10:36 : client: [21] process command "close"
Dec 31 10:36 : client: [21] command returned 4
Dec 31 10:36 : client: [21] closed
Dec 31 10:36 : client: [23] opened from 127.0.0.1:37922
Dec 31 10:36 : client: [23] process command "stop"
Dec 31 10:36 : client: [23] command returned 0
Dec 31 10:36 : client: [23] process command list
Dec 31 10:36 : client: process command "status"
Dec 31 10:36 : client: command returned 0
Dec 31 10:36 : client: process command "currentsong"
Dec 31 10:36 : client: command returned 0
Dec 31 10:36 : client: [23] process command list returned 0
Dec 31 10:36 : client: [23] closed
Dec 31 10:36 : state_file: Saving state file /var/lib/mpd/state

I had to turn the log type to verbose, and then back to normal because it didn't show me anything at first.

Tried to update a few times, as far as I can see, MPD doesn't recieve the order to update the library.

[Zinck84]

The same happens with the Raspberry 2.

[lhkp0480]

I try many time to update MPD but can save the library. Log ask to "check syslog for SEGV" but didn't know how to check and how to fix. The update are in the midle of progress then automatic stop and return to the beginning with empty library. Anybody can help me fix the issue?


20211023 012609 worker: Startup
20211023 012611 worker: Host (moode)
20211023 012611 worker: Hdwr (Pi-3B 1GB)
20211023 012611 worker: Arch (armv7l)
20211023 012611 worker: Kver (4.9.41-v7+)
20211023 012611 worker: Ktyp (Standard)
20211023 012611 worker: Gov (performance)
20211023 012611 worker: OS (moodeOS 3.0)
20211023 012611 worker: Rel (Moode 3.8.4 2017-08-28)
20211023 012611 worker: Upd (None)
20211023 012611 worker: MPD (0.20.10)
20211023 012611 worker: USB boot not enabled yet
20211023 012611 worker: File system expanded
20211023 012611 worker: Session loaded
20211023 012611 worker: Debug logging (off)
20211023 012611 worker: File check...
20211023 012611 worker: File check ok
20211023 012611 worker: USB sources (none attached)
20211023 012611 worker: MPD scheduler policy (time-share)
20211023 012611 worker: MPD started
20211023 012612 worker: wlan0 exists
20211023 012612 worker: wlan0 trying SSID (Linksys06749)
20211023 012612 worker: HDMI port on
20211023 012612 worker: Audio (On-board audio device)
20211023 012612 worker: ALSA outputs unmuted
20211023 012612 worker: ALSA mixer name (PCM)
20211023 012612 worker: MPD volume control (software)
20211023 012612 worker: MPD output 1 ALSA default (on)
20211023 012612 worker: MPD output 2 ALSA crossfeed (off)
20211023 012612 worker: MPD output 3 ALSA equalizer (off)
20211023 012612 worker: Hdwr volume controller exists
20211023 012612 worker: wlan0 (192.168.1.32)
20211023 012612 worker: eth0 exists
20211023 012612 worker: eth0 (192.168.1.17)
20211023 012612 worker: NAS sources (mountall initiated)
20211023 012612 worker: MPD crossfade (off)
20211023 012613 worker: Volume level (0) restored
20211023 012613 worker: Watchdog started
20211023 012613 worker: End startup
20211023 012613 worker: Ready
20211023 012941 worker: Job updmpddb
20211023 015016 watchdog: MPD restarted (check syslog for SEGV)