VOLUMIO on VIM3L, impossible to boot

philippe_kertesz · December 30, 2024, 8:31am

Hello Viraniac,

After 2 hours of scanning, I am getting this error in the mpd.log: Dec 30 08:09 : “client: error on client 2: Output buffer is full”
I guess this maybe the problem as it seems that the logging is stopped (mpd.log size 9558709).

viraniac · December 30, 2024, 8:42am

Thanks, I will share a new image for testing by tomorrow. Hopefully that will solve the issue.

philippe_kertesz · January 7, 2025, 2:08pm

Hello Viraniac,

Do you have some update about your new Volumio image ? It seems that it is not so easy: I hope that you will provide us with it soon

Best regards,

viraniac · January 7, 2025, 2:59pm

There are some blockers that I am working on to resolve. Its taking more time than I expected. But we will release new image soon.

philippe_kertesz · January 17, 2025, 6:47am

Hello Viraniac,

Do you have some update ?

Thank you in advance

philippe_kertesz · February 6, 2025, 6:39am

Hello Viraniac,

I hope that you had nice Chines New Year Holidays !

I hope that you will start again your work on Volumio: thank you to keep us posted !

Best regards,

viraniac · February 6, 2025, 6:56am

@philippe_kertesz I am testing new images, will share by today EOD

viraniac · February 6, 2025, 5:59pm

Please find new images for testing with your NAS - 2025-02-06 - Google Drive

Do note both Vim3 and Vim3l images have issues booting from emmc and hence needs to be flashed on sdcard. Vim4 image is fine though.

philippe_kertesz · February 8, 2025, 8:00am

Hello Viraniac,

I flashed the Volumio image you provided on my mSD: I tried to scan my music Library twice and the final result remains unfortunadely the same (apparently volumio cannot finish the scan and for some reasons I loose the http access).

philippe_kertesz · February 8, 2025, 9:09am

Hello Viraniac,

Just to give you more info: after a while, I reloaded the HTTP page and I could see (at the “source” option of Volumio menu) that no songs had been recorded in the “Music Library” of my VIM3.

viraniac · February 8, 2025, 9:17am

Hi Philippe,

I have tried scanning smb share on my side and it worked. I do have to admit I only had 2-3 songs placed in it and also the share was created on a ubuntu system rather than on a NAS, so it might not be exactly the same scenario.

I found a mention of a bug in mpd documentation related to smb that says its possible that samba share can fail. I wonder if it is the same thing you are experiencing

Anyways, is it possible for you to try the x86 volumio image on a PC and see if that works with your nas. This might help to rule out any upstream issues

philippe_kertesz · February 8, 2025, 9:45am

OK I will try the x64 image. By the way, I have also a raspberry and I never faced such a problem…

viraniac · February 8, 2025, 10:22am

The volumio images for raspberry pi uses raspbian as base, while all other boards and x86 images uses standard debian as base. So experience can be different.

If you wish, I can try creating a raspbian based image and share the same with you to see if that resolves the issue.

philippe_kertesz · February 8, 2025, 11:42am

Hello Viraniac,

I flashed the x86 image on a USB Stick, then I booted on it: non problem for Volumio to scan my NAS and to make songs available and playable…

The only thing that I noticed is that Volumio is supposed to “scan” the drives to which it can connect: in my case, nothing was displayed (like with the Vim3 image) and I entered the NAS IP to mount my music library.

philippe_kertesz · February 8, 2025, 11:43am

If you can create a raspbian image compatible with the VIM3, I will be ready to test it.

viraniac · February 8, 2025, 12:58pm

Please find the link to rasbian based image below.

I haven’t tested the image, but I had tried raspbian as base for vim1s in the past and most of the thinngs were not working. I expect this to be the same.

I would try creating some images with mainline kernel and u-boot for vim3/3l next week, will share the same for testing as well once done

philippe_kertesz · February 9, 2025, 11:30am

Hello Viraniac,

You are right: the raspbian image you created does not work at all: not possible to mount my NAS repertory, and even not possible to hear a radio stream…

philippe_kertesz · February 9, 2025, 2:07pm

Hello Viraniac,

I could activate the Live Log of Volumio and here the log that I got when things started to go weird during my music collection process:

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
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: multiroom , getMultiroom
error: Could not retrieve plugin audio_interface multiroom
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 5
info: CoreCommandRouter::volumioGetVisibleSources
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
[2025/02/09 13:36:26.134596,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.
[2025/02/09 13:36:26.162212,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (3) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.

info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
error: Could not retrieve plugin audio_interface multiroom
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 5
info: CoreCommandRouter::volumioGetVisibleSources
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
info: MPD Database updated - AlbumList cache refreshed
info:
---------------------------- MPD announces state update: database

info:
---------------------------- MPD announces state update: update
info: ControllerMpd::getState

info: ControllerMpd::getState


















 NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/133.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 32









info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 5
info: CoreCommandRouter::volumioGetQueue
info: CoreStateMachine::getQueue
info: CorePlayQueue::getQueue
info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
info: CALLMETHOD: music_service inputs serialMonitorAction [object Object]
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
error: Could not retrieve plugin system_controller motivocontrol

Feb 09 13:58 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Started Music Player Daemon.
MPD restarted due to no mpc output.

info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
error: Could not retrieve plugin audio_interface multiroom
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 5
[2025-02-09 13:58:09] [connect] Successful connection

[2025-02-09 13:58:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739109489 101
[2025/02/09 13:58:16.298699,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.
[2025/02/09 13:58:16.299558,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (3) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.














info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
error: Could not retrieve plugin audio_interface multiroom
info: CoreCommandRouter::volumioGetState
info: CorePlayQueue::getTrack 5
info: CoreCommandRouter::volumioGetVisibleSources
info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
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 , handleBrowseUri
info: CURURI: music-library
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::volumioGetQueue
info: CoreStateMachine::getQueue
info: CorePlayQueue::getQueue
info: Listing playlists
info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
info: CURURI: music-library
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"}
[2025/02/09 14:03:13.897858,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (2) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.
[2025/02/09 14:03:13.898790,  0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response)
  query_name_response: Multiple (3) responses received for a query on subnet 192.168.2.150 for name WORKGROUP<1d>.
  This response was from IP 192.168.2.138, reporting an IP address of 192.168.2.138.
info: CoreCommandRouter::getUIConfigOnPlugin
error: Could not retrieve plugin music_service streaming_services
info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
info: CoreCommandRouter::volumioGetBrowseSources
info: CoreCommandRouter::volumioGetBrowseSources
info: CoreCommandRouter::volumioGetBrowseSources
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 , 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"}

viraniac · February 11, 2025, 1:00pm

I have traced the issue with raspbian based image to this commit - Set default CD playback speed to 1 · volumio/volumio3-backend@a7a6e4e. I started seeing the same issue on new non-raspbian based image I created this week as well.

Created a new raspbian based image without the above change and playback seems to work. In case you wish to try your NAS with the same - Volumio-3.785-2025-02-11-vim3.zip - Google Drive

philippe_kertesz · February 11, 2025, 3:45pm

Hello, I tried your new image: everything goes fine until the music collection is complete: at that stage, it seems that some “reset” happens and I lose everything.

I copied part of my music library (less that half of it) to try to checj if size had some influence but with no success.

I am always getting the same kind of error (there it seems that Volumio is within an infinite loop).:

Starting Live Log…
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: CALLMETHOD: system_controller system enableLiveLog true
info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog
info: Launching a new LiveLog session
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: 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”}