[PROBLEM] moodeaudio 9.3.3 does not put the library

[boomblip]

@alessandro55 Sorry for hijacking your thread.
If you want to downgrade you can download the 9.3.0 release from https://github.com/moode-player/moode/re...4-lite.zip and use e.g. Raspberry Pi Imager to burn it to an SD card.
At least that should allow you to play your music again 

[boomblip]

Upgraded kernel
x@moode:~ $ uname -r
6.12.25+rpt-rpi-2712

Running "update library" now gives
2025-05-18T17:27:35 exception: Read failed: Cannot allocate memory
2025-05-18T17:30:12 exception: Read failed: Cannot allocate memory

And getting the infinite spinner now.
I can play files in the library for now it seems but it won't update.

[boomblip]

Alright. I'm out of time. I hope you can use the info for something. MPD scanning doesn't like pi5 with latest kernel and scanning a library on (apparently only certain) SMB shares (although mine support 2 and 3).
I guess I'll be sticking to 9.3.0 for the foreseeable future.

[Tim Curtis]

No issues mounting SMB share on my Mac. Latest moode.

Code:

20250518 113532 DEBUG: Mount (mount -t cifs "//Mac/_Audio" -o username="guest",password="",rsize=61440,wsize=65536,iocharset=utf8,ro,noserverino,cache=none,dir_mode=0777,file_mode=0777 "/mnt/NAS/Audio")

After "Update library" the FLAC files at that share show up in the Library.

Its not obvious to me what might be happening on your end but maybe someone else can repro your issue and provide some insights.

[boomblip]

Thanks Tim.
Mounting works fine, but updating crashes.
If there are any logs or other commands that I can run through ssh to find causes please share any good ideas.

I've just tested setting up a share on Win11 and from here it works fine, so despite the mounts appearing to be fine from Mac OS 10.13 (and having been up until 6.6 kernels) they are apparently using an SMB that the 6.12 kernels don't like.

If there's any good suggestions out there on how we can learn about the specific issues / causes I'm all ears.

[Tim Curtis]

Samba is not part of the Linux kernel, its a separate user space package.

Troubleshooting database update issues requires a methodical approach rather than just hacking away and seeing if something works.

Your best bet is to start with a fresh 934 image, configure a Remote NAS source for your Mac share, turn on MPD verbose logging in MPD Config screen and then Menu > Update library. Use the commands below to start troubleshooting.

1. Determine of MPD has crashed

Code:

# Examine the moode log for a restart message from watchdog.sh
# "Started MPD after crash detected (check system journal)"
moodeutl -l

2. Examine the MPD log for any errors or warnings

Code:

cat /var/log/mpd/log

[boomblip]

Thanks Tim,

Verbose logging shows:

Code:

2025-05-19T15:43:57 client: [127.0.0.1:47370] client connected
2025-05-19T15:43:57 client: [127.0.0.1:47370] process command "rescan"
2025-05-19T15:43:57 update: spawned thread for update job id 1
2025-05-19T15:43:57 update: starting
2025-05-19T15:43:57 client: [127.0.0.1:47370] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:47370] process command "close"
2025-05-19T15:43:57 client: [127.0.0.1:47370] command returned 4
2025-05-19T15:43:57 client: [127.0.0.1:47370] disconnected
2025-05-19T15:43:57 client: [127.0.0.1:58676] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:58676] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:58704] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:58704] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:48608] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:48608] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:58724] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:58724] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:47662] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:47662] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:58704] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:58704] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:58724] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:58724] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:33798] process command "status"
2025-05-19T15:43:57 client: [127.0.0.1:33798] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:58676] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:58676] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:47662] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:47662] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:48608] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:48608] command returned 0
2025-05-19T15:43:57 client: [127.0.0.1:33798] process command "currentsong"
2025-05-19T15:43:57 client: [127.0.0.1:33798] command returned 0
2025-05-19T15:43:58 update: updating SDCARD/Stereo Test/LRMonoPhase4.flac
2025-05-19T15:43:58 client: [127.0.0.1:48608] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:48608] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:48608] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:48608] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:48608] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:58724] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:58724] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:58724] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:58724] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:58724] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:33798] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:33798] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:33798] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:33798] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:33798] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:47662] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:47662] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:47662] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:47662] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:47662] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:58676] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:58676] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:58676] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:58676] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:58676] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:58704] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:43:58 client: [127.0.0.1:58704] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:58704] process command "close"
2025-05-19T15:43:58 client: [127.0.0.1:58704] command returned 4
2025-05-19T15:43:58 client: [127.0.0.1:58704] disconnected
2025-05-19T15:43:58 client: [127.0.0.1:47372] client connected
2025-05-19T15:43:58 client: [127.0.0.1:47372] process command "status"
2025-05-19T15:43:58 client: [127.0.0.1:47372] command returned 0
2025-05-19T15:43:58 client: [127.0.0.1:47372] process command "idle"
2025-05-19T15:43:58 client: [127.0.0.1:47372] command returned 1
2025-05-19T15:43:58 update: reading NAS/Audio/AQS/0-9/1930s Big Bands - 1987/01 - Casa Loma Orchestra - San Sue Strut.mp3
2025-05-19T15:44:00 client: [127.0.0.1:47388] client connected
2025-05-19T15:44:00 client: [127.0.0.1:47388] process command "status"
2025-05-19T15:44:00 client: [127.0.0.1:47388] command returned 0
2025-05-19T15:44:00 client: [127.0.0.1:47388] process command "currentsong"
2025-05-19T15:44:00 client: [127.0.0.1:47388] command returned 0
2025-05-19T15:44:00 client: [127.0.0.1:47388] process command "lsinfo \"SDCARD/Stereo Test/LRMonoPhase4.flac\""
2025-05-19T15:44:00 client: [127.0.0.1:47388] command returned 0
2025-05-19T15:44:00 client: [127.0.0.1:47388] process command "close"
2025-05-19T15:44:00 client: [127.0.0.1:47388] command returned 4
2025-05-19T15:44:00 client: [127.0.0.1:47388] disconnected
2025-05-19T15:44:00 client: [127.0.0.1:47390] client connected
2025-05-19T15:44:00 client: [127.0.0.1:47390] process command "status"
2025-05-19T15:44:00 client: [127.0.0.1:47390] command returned 0
2025-05-19T15:44:00 client: [127.0.0.1:47390] process command "idle"
2025-05-19T15:44:00 client: [127.0.0.1:47390] command returned 1
2025-05-19T15:44:01 client: [127.0.0.1:47394] client connected
2025-05-19T15:44:01 client: [127.0.0.1:47394] process command "status"
2025-05-19T15:44:01 client: [127.0.0.1:47394] command returned 0
2025-05-19T15:44:01 client: [127.0.0.1:47394] process command "close"
2025-05-19T15:44:01 client: [127.0.0.1:47394] command returned 4
2025-05-19T15:44:01 client: [127.0.0.1:47394] disconnected
2025-05-19T15:44:04 client: [127.0.0.1:50064] client connected
2025-05-19T15:44:04 client: [127.0.0.1:50064] process command "status"
2025-05-19T15:44:04 client: [127.0.0.1:50064] command returned 0
2025-05-19T15:44:04 client: [127.0.0.1:50064] process command "close"
2025-05-19T15:44:04 client: [127.0.0.1:50064] command returned 4
2025-05-19T15:44:04 client: [127.0.0.1:50064] disconnected
2025-05-19T15:44:07 client: [127.0.0.1:50066] client connected
2025-05-19T15:44:07 client: [127.0.0.1:50066] process command "status"
2025-05-19T15:44:07 client: [127.0.0.1:50066] command returned 0
2025-05-19T15:44:07 client: [127.0.0.1:50066] process command "close"
2025-05-19T15:44:07 client: [127.0.0.1:50066] command returned 4
2025-05-19T15:44:07 client: [127.0.0.1:50066] disconnected
2025-05-19T15:44:10 client: [127.0.0.1:50080] client connected
2025-05-19T15:44:10 client: [127.0.0.1:50080] process command "status"
2025-05-19T15:44:10 client: [127.0.0.1:50080] command returned 0
2025-05-19T15:44:10 client: [127.0.0.1:50080] process command "close"
2025-05-19T15:44:10 client: [127.0.0.1:50080] command returned 4
2025-05-19T15:44:10 client: [127.0.0.1:50080] disconnected
2025-05-19T15:44:13 client: [127.0.0.1:42108] client connected
2025-05-19T15:44:13 client: [127.0.0.1:42108] process command "status"
2025-05-19T15:44:13 client: [127.0.0.1:42108] command returned 0
2025-05-19T15:44:13 client: [127.0.0.1:42108] process command "close"
2025-05-19T15:44:13 client: [127.0.0.1:42108] command returned 4
2025-05-19T15:44:13 client: [127.0.0.1:42108] disconnected
2025-05-19T15:44:16 client: [127.0.0.1:42118] client connected
2025-05-19T15:44:16 client: [127.0.0.1:42118] process command "status"
2025-05-19T15:44:16 client: [127.0.0.1:42118] command returned 0
2025-05-19T15:44:16 client: [127.0.0.1:42118] process command "close"
2025-05-19T15:44:16 client: [127.0.0.1:42118] command returned 4
2025-05-19T15:44:16 client: [127.0.0.1:42118] disconnected
2025-05-19T15:44:19 client: [127.0.0.1:42132] client connected
2025-05-19T15:44:19 client: [127.0.0.1:42132] process command "status"
2025-05-19T15:44:19 client: [127.0.0.1:42132] command returned 0
2025-05-19T15:44:19 client: [127.0.0.1:42132] process command "close"
2025-05-19T15:44:19 client: [127.0.0.1:42132] command returned 4
2025-05-19T15:44:19 client: [127.0.0.1:42132] disconnected
2025-05-19T15:44:22 client: [127.0.0.1:49050] client connected
2025-05-19T15:44:22 client: [127.0.0.1:49050] process command "status"
2025-05-19T15:44:22 client: [127.0.0.1:49050] command returned 0
2025-05-19T15:44:22 client: [127.0.0.1:49050] process command "close"
2025-05-19T15:44:22 client: [127.0.0.1:49050] command returned 4
2025-05-19T15:44:22 client: [127.0.0.1:49050] disconnected
2025-05-19T15:44:25 client: [127.0.0.1:49052] client connected
2025-05-19T15:44:25 client: [127.0.0.1:49052] process command "status"
2025-05-19T15:44:25 client: [127.0.0.1:49052] command returned 0
2025-05-19T15:44:25 client: [127.0.0.1:49052] process command "close"
2025-05-19T15:44:25 client: [127.0.0.1:49052] command returned 4
2025-05-19T15:44:25 client: [127.0.0.1:49052] disconnected
2025-05-19T15:44:28 client: [127.0.0.1:49058] client connected
2025-05-19T15:44:28 client: [127.0.0.1:49058] process command "status"
2025-05-19T15:44:28 client: [127.0.0.1:49058] command returned 0
2025-05-19T15:44:28 client: [127.0.0.1:49058] process command "close"
2025-05-19T15:44:28 client: [127.0.0.1:49058] command returned 4
2025-05-19T15:44:28 client: [127.0.0.1:49058] disconnected

And then it keeps repeating the same pattern using new ports each time.

[boomblip]

I ended up buying a USB stick to store my music on instead. Alternatively if anyone reads this I would recommend upgrading the SMB server where possible. Unfortunately that was not an option for me.

[Nutul]

(...) I would recommend upgrading the SMB server where possible (...)

IMHO it would have sufficed to check the involved SMB protocol versions, and a choice been made accordingly.
In your specific case, searching on forums about MAC + SMB issues would have been appropriate.
Not to dismiss your issue in any manner, but there are hundreds of users out there (I for one) that have their libraries on perfectly working SMB shares...

[boomblip]

(...) I would recommend upgrading the SMB server where possible (...)

IMHO it would have sufficed to check the involved SMB protocol versions, and a choice been made accordingly.
In your specific case, searching on forums about MAC + SMB issues would have been appropriate.
Not to dismiss your issue in any manner, but there are hundreds of users out there (I for one) that have their libraries on perfectly working SMB shares...

Hi Nutul. This is exactly what I did to begin with. I tested various “vers=x.x” fitting with the supported dialects of my SMB share and it led nowhere.
I do not doubt there are working shares out there. I even mention in this thread that it works fine with a share from win11.
The point is that the smb protocol implementation of vers makes it a looping process to ensure you test all possible definitions and even then it wouldn’t work. So, if I could I would update the server. SMB should be able to auto-negotiate.
Furthermore: this is introduced by or along the kernel updates in the recent releases.
Maybe someone better at Linux and SMB can help pinpoint the cause at some point.
And last but not least MPD can’t see what’s happening (not even in verbose) so everything points to it being a subsystem. The only thing you can perhaps pin on MPD is that it goes into infinite spinner instead of timing out on the process.