Hello, I am using Volumio 3.611 on two RASP4 2G with an USB stick mounted as NAS on a Fritzbox 7490. That was working fine so far.However, after the latest Volumio upgrade or the mounting as network drive under windows 11 this has stopped working. The Raspis were reading the Musiclibrary but under Interprets and so on I did not see any content. Now I have removed the network drive under one Volumio and set it up newly. Now this is showing the drive as active, But is not loading any data. So it is somehow worse than before. In the Live Log I see:
Starting Live Log…
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
cifs_open_info_to_fattr: 30 callbacks suppressed
CIFS: VFS: bogus file nlink value 0
…
I tried with (SMB) vers=1.0 , vers=3.0 …no difference
I have read a lot about similar problems, but I found no solution. Any suggestions?
The network drive in Windows 11 is working fine. I was just wondering if that has changed something in a way that the Raspi has these mounting problems now…
This appears to be a kernel regression with smb version 1.0 and RPi.
I have seen this with kernel version 6.1.2 with an x86 version, but was fixed ages ago. I will inform my dev colleagues and discuss it.
Edit
We checked the kernel, it appears to be a different issue, albeit with the same error message “CIFS: VFS: bogus file nlink value 0”
Could you make a screenshot of the used Volumio network source parameters?
Ok, you definitely need “vers=1.0” with the Fritzbox.
After a fresh boot, could you try with “vers=1.0” and when that does not work, try with “noserverino,vers=1.0”?
You may have to reboot inbetween.
Hello, I guess that these settings are fine: noserverino,vers=1.0
It is connected. However. sometimes it reads some data, But it is not building a local music data structure. So ‘Artits’ has still no entry. The green loading bar at the top is moving, but nothing happens. When I go back to sources then or restart: it is empty again. Saying 0 artits, 0 albums, … or not even a single number.
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/FB
pam_unix(sudo:session): session opened for user root by (uid=0)
mnt-NAS-FB.mount: Succeeded.
pam_unix(sudo:session): session closed for user root
CIFS: VFS: RFC1001 size 39 smaller than SMB for mid=15212
00000000: 27000000 424d53ff 00000074 40018800 ...'.SMBt......@
00000010: 00000000 00000000 00000000 11cf0000 ................
00000020: f5 01 6c 3b 03 ff 00 00 00 00 00 ..l;.......
CIFS: VFS: \\192.168.212.1 cifs_put_smb_ses: Session Logoff failure rc=-5
info: Share FB successfully unmounted
volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=VOLUMIO2,password=xxxxxxxxx,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,noserverino, vers=1.0 //192.168.212.1/FRITZ.NAS\Verbatim-STORENGO-01\Music /mnt/NAS/FB
pam_unix(sudo:session): session opened for user root by (uid=0)
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
pam_unix(sudo:session): session closed for user root
info: An error occurred mounting the new share. Rolling back configuration
cifs: Unknown parameter ' vers'
info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
info: CoreCommandRouter::executeOnPlugin: networkfs , editShare
info: Share FB successfully unmounted
volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=VOLUMIO2,password=xxxxxxxx,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,noserverino,vers=1.0 //192.168.212.1/FRITZ.NAS\Verbatim-STORENGO-01\Music /mnt/NAS/FB
pam_unix(sudo:session): session opened for user root by (uid=0)
Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
CIFS: VFS: Use of the less secure dialect vers=1.0 is not recommended unless required for access to very old servers
CIFS: Attempting to mount \\192.168.212.1\FRITZ.NAS
pam_unix(sudo:session): session closed for user root
Starting Music Player Daemon...
root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
pam_unix(sudo:session): session opened for user root by (uid=0)
pam_unix(sudo:session): session closed for user root
Feb 07 10:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Started Music Player Daemon.
info: [1707296501934] Database update started
info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
error: MPD error: Error: This socket has been ended by the other party
error: This socket has been ended by the other party {"code":"EPIPE"}
I’m having the same problem - Fritzbox 7530, two PIs - (2 model B and a Zero 2wh).
Both were working fine until I updated a few days ago.
Music library vanished and the output now cuts off after about 10 mins. (Devices are completely useless with newest update(s) - pretty sure there was another update in the last couple of days?)
Found this thread by searching the erros I got - I get both errors as OP for both cifs and MPD.
When updating library it adds 20%-30% of the music before going blank again.
Adding noserino means it gets a bit further in rebuilding the database before going blank again.
It also misreads the capacity of the NAS drive as 15tb instead of 190gb.
I was tearing my hair out and rebuilding both devices to find the issue, but I’ve now reverted to an image I had from Oct last year and kept auto update turned off (version 3.512 from Jun 2023). This appears to work fine for both issues (I have it playing now and will update if it fails and produces the MPD error again).
Please don’t say “same problem” without leaving a log, give us a chance.
We need facts and any further “same problem” log helps either to get a clearer picture or ideally to solve it.
Apologies, I have a spare Pi that I can test with tomorrow and get a log.
(I just had the same symptoms and found both
CIFS: VFS: bogus file nlink value 0
And
error: MPD error: Error: This socket has been ended by the other party
When I skimmed through the live log.)
3.616 is even worse. It is showing now wrong NAS size as well - 15 TB instead of 134 GB in version 3.611. The other error is still there… Should I wait or downgrade?
Rebuilt a fresh device (from the Oct image), added fileshare and a radio stream/setup wireless etc… All confirmed working and stable (505 albums).
Clicked update and let it restart.
On restart the music database was blank but rescanning (drive size wrong again - 15tb instead of 190gb). It got about 50% of the way through (about 227 the last time I saw it change).
Radio stopped and database went blank again.
I’ve added the log below - I uploaded it shortly after the audio dropped and db went blank (they seemed to happen quite close together if not at same time?).
Starting Live Log…
info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction
info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs
info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object]
info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
ls: cannot access ‘/var/log/samba/log.wb-VOLUMIO’: No such file or directory
ls: cannot access ‘WZ’: No such file or directory
info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
info:
---------------------------- MPD announces state update: update
info: sendMpdCommand rescan took 3 milliseconds
info: ControllerMpd::getState
info: sendMpdCommand status took 4 milliseconds
info: sendMpdCommand status took 1 milliseconds
info: Command Router : Notfying DB Updatetrue
info:
---------------------------- MPD announces state update: update
info: ControllerMpd::getState
info:
---------------------------- MPD announces state update: update
info: ControllerMpd::getState
info: sendMpdCommand playlistinfo took 5 milliseconds
info: sendMpdCommand status took 3 milliseconds
info: sendMpdCommand status took 3 milliseconds
info: sendMpdCommand status took 2 milliseconds
info: sendMpdCommand status took 2 milliseconds
info: Command Router : Notfying DB Updatetrue
info: CoreCommandRouter::Close All Modals sent
info: Command Router : Notfying DB Updatetrue
info: CoreCommandRouter::Close All Modals sent
info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 0
info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
info: ------------------------------ 103ms
info: sendMpdCommand playlistinfo took 31 milliseconds
info: sendMpdCommand playlistinfo took 9 milliseconds
info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 0
info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
info: ControllerMpd::pushState
info: CoreCommandRouter::servicePushState
info: CorePlayQueue::getTrack 0
info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
info: ------------------------------ 65ms
info: ------------------------------ 63ms
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
cifs_open_info_to_fattr: 67 callbacks suppressed
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
cifs_open_info_to_fattr: 66 callbacks suppressed
CIFS: VFS: bogus file nlink value 0
CIFS: VFS: bogus file nlink value 0
…
we’re currently performing internal QA testing on v3629, for releasing it as the new stable version.
@Skuzzzy from your log, I can see a lot of problems with your WiFi connection, could you please try to connect the device with an verify if the problem is solved in this way?
