06-19-2025, 11:22 PM
(06-19-2025, 05:46 PM)Yawarakaimono Wrote: Regenerate database finishes without error.
Update a folder works. Several times.
But after reboot Update a folder gives the "Library update is already in progress." message.
Does this present new information?
Log file:
Code:20250619 193311 worker: --
20250619 193311 worker: -- Start moOde 9 series
20250619 193311 worker: --
20250619 193311 worker: Daemonize: complete
20250619 193311 worker: Linux startup: complete
20250619 193311 worker: Boot config: ok
20250619 193311 worker: Boot folder: ok
20250619 193311 worker: Radio table: ok
20250619 193313 worker: PHP session: cleaned
20250619 193313 worker: PHP session: loaded
20250619 193317 worker: Package locks: applied
20250619 193318 worker: File check: complete
20250619 193318 worker: Log to RAM: on
20250619 193318 worker: Tmp to RAM: on
20250619 193318 worker: Debug logging: off
20250619 193318 worker: --
20250619 193318 worker: -- System
20250619 193318 worker: --
20250619 193321 worker: Host name: Sennheiser
20250619 193321 worker: RPi model: Pi-3B 1.2 1GB
20250619 193321 worker: moOde release: 9.3.6 2025-06-06
20250619 193321 worker: RaspiOS: 12.10 Bookworm 64-bit
20250619 193321 worker: Linux Kernel: 6.12.25 64-bit
20250619 193321 worker: MPD version: 0.24.4
20250619 193321 worker: User id: pi
20250619 193321 worker: Home folder: /home/pi
20250619 193321 worker: Time zone: Europe/Amsterdam
20250619 193321 worker: Kbd layout: us
20250619 193321 worker: HDMI ports(s): on
20250619 193321 worker: HDMI-CEC: off
20250619 193321 worker: Sys LED0: on
20250619 193321 worker: Sys LED1: on
20250619 193321 worker: Reduce power: n/a
20250619 193321 worker: Fan control: n/a
20250619 193321 worker: CPU governor: ondemand
20250619 193321 worker: --
20250619 193321 worker: -- Network
20250619 193321 worker: --
20250619 193321 worker: Eth0
20250619 193321 worker: Ethernet: adapter exists
20250619 193321 worker: Ethernet: timeout off
20250619 193321 worker: Ethernet: method dhcp
20250619 193321 worker: Ethernet: address 192.168.188.40
20250619 193321 worker: Ethernet: netmask 255.255.255.0
20250619 193321 worker: Ethernet: gateway 192.168.188.1
20250619 193321 worker: Ethernet: pri DNS 192.168.188.1
20250619 193321 worker: Ethernet: domain fritz.box
20250619 193321 worker: Wlan0
20250619 193321 worker: Wireless: adapter does not exist
20250619 193321 worker: mDNS
20250619 193321 worker: Discover: IPv4 and IPv6
20250619 193321 worker: --
20250619 193321 worker: -- File sharing
20250619 193321 worker: --
20250619 193321 worker: SMB file sharing: off
20250619 193321 worker: SMB password: none
20250619 193321 worker: NFS file sharing: off
20250619 193321 worker: DLNA file sharing: off
20250619 193321 worker: --
20250619 193321 worker: -- Special configs
20250619 193321 worker: --
20250619 193321 worker: Plexamp: not installed
20250619 193321 worker: RoonBridge: not installed
20250619 193321 worker: Allo Boss 2: not detected, OLED script ok
20250619 193321 worker: Allo Piano 2.1: not detected
20250619 193321 worker: IQaudIO DigiAMP+: not detected
20250619 193321 worker: RPi DigiAMP+: not detected
20250619 193321 worker: Bluetooth: session vars ok
20250619 193321 worker: --
20250619 193321 worker: -- ALSA debug
20250619 193321 worker: --
20250619 193321 worker: Cards: 0:vc4hdmi 1:sndrpihifiberry 2:empty 3:empty
20250619 193321 worker: 4:empty 5:empty 6:empty 7:empty
20250619 193322 worker: Mixers: 0:none 1:none 2:none 3:none
20250619 193322 worker: 4:none 5:none 6:none 7:none
20250619 193322 worker: --
20250619 193322 worker: -- Audio configuration
20250619 193322 worker: --
20250619 193322 worker: Audio device: 1:HiFiBerry Digi+ Pro
20250619 193322 worker: ALSA card: has not been reassigned
20250619 193322 worker: MPD config: update not needed
20250619 193322 worker: ALSA output: I2S
20250619 193322 worker: ALSA mode: Direct (hw)
20250619 193322 worker: ALSA mixer: none exists
20250619 193325 worker: ALSA volume: controller not detected
20250619 193325 worker: ALSA maxvol: 100%
20250619 193325 worker: ALSA loopback: off
20250619 193325 worker: MPD mixer Fixed (0dB)
20250619 193325 worker: Integ audio: vc4-kms-v3d
20250619 193326 worker: Audio formats: S16_LE, S24_LE
20250619 193326 worker: CamillaDSP: off
20250619 193326 worker: CDSP volume: 0dB
20250619 193326 worker: CDSP volrange: 60dB
20250619 193326 worker: --
20250619 193326 worker: -- MPD startup
20250619 193326 worker: --
20250619 193327 worker: MPD service: started
20250619 193327 worker: MPD port 6600: accepting connections
20250619 193327 worker: MPD ALSA Default: on
20250619 193327 worker: MPD ALSA Bluetooth: off
20250619 193327 worker: MPD HTTP Server: off
20250619 193327 worker: MPD volume: 0
20250619 193327 worker: Saved MPD vol: -1
20250619 193327 worker: Saved SRC vol: 0
20250619 193327 worker: MPD crossfade: off
20250619 193327 worker: MPD ignore CUE: no
20250619 193327 worker: MPD CDSP volsync: off
20250619 193328 worker: --
20250619 193328 worker: -- Music sources
20250619 193328 worker: --
20250619 193328 worker: USB drives: none
20250619 193328 worker: NVMe drives: none
20250619 193328 worker: SATA drives: none
20250619 193328 worker: NAS source: Fafnir (smb)
20250619 193328 worker: NAS source: Fasolt (smb)
20250619 193329 worker: --
20250619 193329 worker: -- Feature availability
20250619 193329 worker: --
20250619 193329 worker: Input select: available, Source: MPD, Output: HiFiBerry Digi+ Pro
20250619 193329 worker: Bluetooth: available, PIN: None, ALSA/CDSP maxvol: 100%/0dB, ALSA outmode: Standard
20250619 193329 worker: AirPlay: available
20250619 193329 worker: Spotify Connect: available
20250619 193329 worker: Deezer Connect: n/a
20250619 193329 worker: Squeezelite: available
20250619 193329 worker: UPnP client: available
20250619 193329 worker: Plexamp: not installed, ALSA maxvol: 100%
20250619 193329 worker: RoonBridge: not installed
20250619 193329 worker: Multiroom: available
20250619 193329 worker: GPIO buttons: available
20250619 193329 worker: Stream recorder: n/a
20250619 193329 worker: --
20250619 193329 worker: -- Security
20250619 193329 worker: --
20250619 193329 worker: Web SSH server: on
20250619 193329 worker: XSS detection: off
20250619 193329 worker: HTTPS mode: available
20250619 193329 worker: --
20250619 193329 worker: -- Peripherals
20250619 193329 worker: --
20250619 193351 worker: Local display: off
20250619 193351 worker: Chromium ver: 126.0.6478.164-rpt1
20250619 193351 worker: Chromium cfg: directory ok
20250619 193351 worker: Screen blank 600
20250619 193351 worker: On-screen kbd: off
20250619 193351 worker: Disable GPU: off
20250619 193351 worker: HDMI orient: landscape
20250619 193351 worker: HDMI 4K 60Hz: off
20250619 193351 worker: DSI scn type: none
20250619 193351 worker: DSI port: 1
20250619 193351 worker: DSI brightness: 255
20250619 193351 worker: DSI rotate: 0
20250619 193351 worker: Rotary encoder: off
20250619 193351 worker: Triggerhappy: off
20250619 193351 worker: LCD updater: off
20250619 193351 worker: --
20250619 193351 worker: -- Miscellaneous
20250619 193351 worker: --
20250619 193351 worker: Software update: Automatic check on
20250619 193351 worker: Software update: Checking for available update...
20250619 193352 worker: Software update: Software is up to date
20250619 193352 worker: Auto-CoverView: off
20250619 193352 worker: CoverView timeout: Never
20250619 193352 worker: Auto-shuffle: off
20250619 193352 worker: Auto-play: off
20250619 193352 worker: Maintenance task: 360 mins
20250619 193352 worker: Current view: reset to Playback
20250619 193352 worker: Active flags: all false
20250619 193352 worker: Reset flags: skipped
20250619 193352 worker: Session check: ok
20250619 193352 worker: --
20250619 193352 worker: -- Startup complete
20250619 193352 worker: --
20250619 193352 worker: Mount monitor: started
20250619 193352 worker: Radio monitor: off
20250619 193352 worker: Watchdog monitor: started
20250619 193352 worker: Responsiveness: Boosted
20250619 193352 worker: Ready
I don't see anything suspicious in the log.
I'm not able to reproduce the issue but here's some log output from one of my test collections for successful "Update this folder" and "Update library".
Your log should look similar if the library updates are completing successfully. Note that the Thumbnail Generator and MPD database update run parallel to one another so one may complete before the other and thats fine.
Code:
.
.
.
20250619 181310 worker: --
20250619 181310 worker: -- Startup complete
20250619 181310 worker: --
20250619 181310 worker: Mount monitor: started
20250619 181310 worker: Radio monitor: off
20250619 181310 worker: Watchdog monitor: started
20250619 181310 worker: Responsiveness: Default
20250619 181310 worker: Ready
20250619 181310 worker: Ready script enabled
20250619 181310 worker: Script started
20250619 181310 worker: Script result: Play OSDISK/System Sounds/ReadyChime.flac
20250619 181310 worker: Script finished
# 1 "Update this folder"
20250619 183508 worker: Job update_library
20250619 183508 worker: Clear Library tag cache
20250619 183508 mpdindex: Start
20250619 183508 mpdindex: Cmd (update "NAS/TRX-FLAC/Aaron Parks")
20250619 183508 worker: Update thumbnail cache
20250619 183508 thumb-gen: Start
20250619 183508 thumb-gen: Scan opt: Default+
20250619 183508 thumb-gen: Priority: Embedded cover
20250619 183508 thumb-gen: Res,Qual: 600px,60
20250619 183508 thumb-gen: Px ratio: 1
20250619 183508 thumb-gen: Th width: 600
20250619 183508 thumb-gen: Thm qual: 60
20250619 183508 thumb-gen: Scanning: NAS, NVME, OSDISK, SATA, SDCARD
20250619 183511 mpdindex: Done: indexed 311 artists, 404 albums, 4489 songs
20250619 183511 worker: Job update_library done
20250619 183515 thumb-gen: Done: 405 folders scanned, 0 thumbs created, 405 already in cache.
# 2 "Update this folder"
20250619 183526 worker: Job update_library
20250619 183526 worker: Clear Library tag cache
20250619 183526 mpdindex: Start
20250619 183526 mpdindex: Cmd (update "NAS/TRX-FLAC/Alan Pasqua")
20250619 183526 worker: Update thumbnail cache
20250619 183526 thumb-gen: Start
20250619 183526 thumb-gen: Scan opt: Default+
20250619 183526 thumb-gen: Priority: Embedded cover
20250619 183526 thumb-gen: Res,Qual: 600px,60
20250619 183526 thumb-gen: Px ratio: 1
20250619 183526 thumb-gen: Th width: 600
20250619 183526 thumb-gen: Thm qual: 60
20250619 183526 thumb-gen: Scanning: NAS, NVME, OSDISK, SATA, SDCARD
20250619 183529 thumb-gen: Done: 405 folders scanned, 0 thumbs created, 405 already in cache.
20250619 183529 mpdindex: Done: indexed 311 artists, 404 albums, 4489 songs
20250619 183529 worker: Job update_library done
# 3 "Update this folder"
20250619 183553 worker: Job update_library
20250619 183553 worker: Clear Library tag cache
20250619 183553 mpdindex: Start
20250619 183553 mpdindex: Cmd (update "NAS/TRX-FLAC/Alex Cortiz")
20250619 183553 worker: Update thumbnail cache
20250619 183553 thumb-gen: Start
20250619 183553 thumb-gen: Scan opt: Default+
20250619 183553 thumb-gen: Priority: Embedded cover
20250619 183553 thumb-gen: Res,Qual: 600px,60
20250619 183553 thumb-gen: Px ratio: 1
20250619 183553 thumb-gen: Th width: 600
20250619 183553 thumb-gen: Thm qual: 60
20250619 183553 thumb-gen: Scanning: NAS, NVME, OSDISK, SATA, SDCARD
20250619 183555 thumb-gen: Done: 405 folders scanned, 0 thumbs created, 405 already in cache.
20250619 183556 mpdindex: Done: indexed 311 artists, 404 albums, 4489 songs
20250619 183556 worker: Job update_library done
# 4 "Update library"
20250619 183648 worker: Job update_library
20250619 183648 worker: Clear Library tag cache
20250619 183648 mpdindex: Start
20250619 183648 mpdindex: Cmd (update)
20250619 183648 worker: Update thumbnail cache
20250619 183648 thumb-gen: Start
20250619 183648 thumb-gen: Scan opt: Default+
20250619 183648 thumb-gen: Priority: Embedded cover
20250619 183648 thumb-gen: Res,Qual: 600px,60
20250619 183648 thumb-gen: Px ratio: 2
20250619 183648 thumb-gen: Th width: 600
20250619 183648 thumb-gen: Thm qual: 60
20250619 183648 thumb-gen: Scanning: NAS, NVME, OSDISK, SATA, SDCARD
20250619 183652 thumb-gen: Done: 405 folders scanned, 0 thumbs created, 405 already in cache.
20250619 183700 mpdindex: Done: indexed 311 artists, 404 albums, 4489 songs
20250619 183700 worker: Job update_library done
Another troubleshooting approach is to look at the GLOBAL flags in the front-end (WebUI) that determine whether it thinks a Library update is still running.
- Open your Browsers Javascript console
- At the prompt type in GLOBAL.libLoading; <return> and GLOBAL.libRendered; <return>
- They will either be true or false depending on whether the front-end thinks the Library update and Library rendering in the WebUI are complete.
The function below in file /var/www/js/playerlib.js is responsible for displaying "Library update in progress".
Code:
function submitLibraryUpdate (path = '') {
if (GLOBAL.libLoading == false) {
GLOBAL.libLoading = true;
// DEBUG:
//console.log('submitLibraryUpdate(): GLOBAL.libLoading: ' + GLOBAL.libLoading);
GLOBAL.libRendered = false;
$.getJSON('command/music-library.php?cmd=update_library', {'path': path}, function(data) {
//console.log(data);
});
notify(NOTIFY_TITLE_INFO, 'update_library', (path == '' ? '' : '<br>' + path));
}
else {
notify(NOTIFY_TITLE_ALERT, 'library_updating');
}
}
The function submits the command 'update_library' and and optional path to /var/www/command/music-library.php. This script in turn submits the 'update_library' job to /var/www/daemon/worker.php which starts MPD database update and thumbnail generator. If those complete successfully log entries are written to the moode log and the GlOBAL flags are set appropriately.
If this code in the function never returns or bombs then the GLOBAL flags prolly won't be set correctly. I'm not able to get this to fail in my environment so I have no insights.
Code:
$.getJSON('command/music-library.php?cmd=update_library', {'path': path}, function(data) {
//console.log(data);
});
Food for thought :-)