Hi - have been using Volumio happily for over a year and encountered a problem over the weekend, hope you can help…
General setup
Volumio 2.873 running on Raspberry pi Zero, Hifiberry Amp with 18v power supply that powers the pi.
I use Volumio primarily for Radio and podcasts. I have written a podcatcher in python that reads RSS files and generated Volumio Playlist files with the URLs of the podcasts - it does not download the episodes to the pi.
This has been working well for over a year.
Issue
Volumio no longer plays media, either radio stations (either my own or Volumio’s own list of stations) or the remote podcasts from the Playlists (see above). I have found previously that if I try to play a stream that does not exist I get a red error notification but I don’t get any notifications that anything is wrong.
What I did at the weekend
- Through the Volumio UI I tried a system update. This failed, telling me that as I had user files on the system it could not update. Volumio reports the system version is now 2.873
- Through the Volumio UI I changed the hostname to Volumio-Kitchen, as I am intending to set up a second Volumio system on the network. This appeared to work in that after a reboot I could access the Volumio UI via http://volumio-kitchen.local. At this stage I did not try playing any media, but the next day I discovered that media no longer played. I changed the hostname back to Volumio, rebooted, and the UI is available at volumio.local again.
Tests
To diagnose what’s up I have tried a few things:
- Upon reboot Volumio plays the start up jingle through the speakers, so fundamentally Volumio is still communicating with the Hifiberry amp
- speaker-test and aplay are also still able to play via the amp, so the Hifiberry amp and the ALSA setup still seem OK
- Using the API, getstate tells me the status is ‘stop’. If I click on different radio stations/playlist items through the UI, getstate shows it recognises that the current item in the queue has changed. Also using the API to ‘play’ it returns me a success status (“play success”) but still nothing plays.
- The python script that generates the Playlist files still runs overnight and I can successfully run it manually without errors, so the system appears to have internet access and seems generally OK.
I’d be grateful for any suggestions for what to check or change, please let me know if there’s any other useful info I can post. Thanks!
Simon
Edit: I’ve managed to run journalctl and got the following log messages when I try to play one of my radio streams:
Jun 21 17:25:24 volumio volumio[8131]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jun 21 17:25:26 volumio volumio[8131]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::volumioReplaceandPlayItems
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::ClearQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::stop
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 21 17:25:32 volumio volumio[8131]: UNSET VOLATILE
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::clearPlayQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::saveQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::volumioPushState
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::volumioPushQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::addQueueItems
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::addQueueItems
Jun 21 17:25:32 volumio volumio[8131]: info: Adding Item to queue: http://open.live.bbc.co.uk/mediaselector/5/select/version/2.0/mediaset/http-icy-mp3-a/vpid/bbc_radio_four_extra/format/pls.pls
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::saveQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::volumioPushQueue
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::updateTrackBlock
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::getTrackBlock
Jun 21 17:25:32 volumio volumio[8131]: info: CoreCommandRouter::volumioPlay
Jun 21 17:25:32 volumio volumio[8131]: UNSET VOLATILE
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::play index 0
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::stop
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 21 17:25:32 volumio volumio[8131]: UNSET VOLATILE
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::play index undefined
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::setConsumeUpdateService undefined
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::getTrack 0
Jun 21 17:25:32 volumio volumio[8131]: info: CoreStateMachine::startPlaybackTimer
Jun 21 17:25:32 volumio volumio[8131]: info: CorePlayQueue::getTrack 0
Jun 21 17:25:32 volumio volumio[8131]: info: [1655832332563] ControllerWebradio::clearAddPlayTrack
Jun 21 17:25:32 volumio volumio[8131]: verbose: ControllerMpd::sendMpdCommand stop
Jun 21 17:25:53 volumio wpa_supplicant[970]: wlan0: WPA: Group rekeying completed with 20:b0:01:fa:35:1b [GTK=CCMP]
Jun 21 17:25:59 volumio volumio[8131]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600
Jun 21 17:26:54 volumio smbd[8097]: [2022/06/21 17:26:54.779013, 0] …/lib/util/debug.c:603(reopen_logs_internal)
Jun 21 17:26:54 volumio smbd[8097]: Unable to open new log file ‘/var/log/samba/log.smbd’: No such file or directory
Jun 21 17:28:29 volumio volumio[8131]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600