No plugins available

Volumio Information

Volumio Version: 2.806
Hardware: Raspberry Pi Zero W
DAC: Justboom pHat DAC

I’ve just installed Volumio2 after using Volumio1 for a few years.and it’s working, I can play web radio stations.

I want to install the spotify plugin however when I go to the plugin section, there are no plugins listed (see attached screenshot).

Am I doing something wrong?

Screenshot

Could you share some logs?

Either from the /dev page using the livelog feature or via ssh.

# Volumio logs
journalctl -f 

Do this just before heading to the plugin page to try and isolate your issue.

Note you might need sudo before these commands depending on the version of Volumio your running

Ah, excellent, something to try, thank you :slight_smile:

Ok, so I've booted fresh and then logged in via ssh to check the logs:

-- Logs begin at Fri 2020-09-11 11:27:42 UTC. --
Sep 11 16:09:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Sep 11 16:09:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Sep 11 16:09:26 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 11 16:09:29 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Sep 11 16:09:37 volumio volumio[862]: info: CoreCommandRouter::volumioGetState
Sep 11 16:09:37 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:09:37 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue
Sep 11 16:09:37 volumio volumio[862]: info: CoreStateMachine::getQueue
Sep 11 16:09:37 volumio volumio[862]: info: CorePlayQueue::getQueue
Sep 11 16:09:37 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid
  
Then I refresh the volumio.local page and get:

Sep 11 16:12:02 volumio sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 11 16:12:02 volumio sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 11 16:12:02 volumio sudo[1586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 11 16:12:02 volumio sudo[1586]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 11 16:12:02 volumio sudo[1584]: pam_unix(sudo:session): session closed for user root
Sep 11 16:12:02 volumio sudo[1586]: pam_unix(sudo:session): session closed for user root
Sep 11 16:12:03 volumio sudo[1606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Sep 11 16:12:03 volumio sudo[1606]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 11 16:12:03 volumio sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Sep 11 16:12:03 volumio sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0)
Sep 11 16:12:03 volumio sudo[1606]: pam_unix(sudo:session): session closed for user root
Sep 11 16:12:03 volumio sudo[1608]: pam_unix(sudo:session): session closed for user root
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::volumioGetState
Sep 11 16:12:04 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::volumioGetVisibleSources
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Sep 11 16:12:04 volumio volumio[862]: info: Listing playlists
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Sep 11 16:12:04 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Sep 11 16:12:06 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus

Then when I click on Plugins I get:

Sep 11 16:14:18 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware
Sep 11 16:14:19 volumio volumio[862]: info: Could not detect Volumio Device: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Sep 11 16:15:19 volumio volumio[862]: info: Cannot download Available plugins list: Error: socket hang up

If however I then click on web radio and pick one from random, it plays and the log shows:

Sep 11 16:16:13 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Sep 11 16:16:13 volumio volumio[862]: info: CoreCommandRouter::volumioGetQueue
Sep 11 16:16:13 volumio volumio[862]: info: CoreStateMachine::getQueue
Sep 11 16:16:13 volumio volumio[862]: info: CorePlayQueue::getQueue
Sep 11 16:16:23 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Sep 11 16:16:33 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri
Sep 11 16:16:33 volumio volumio[862]: info: TuneIn handleBrowseUri: tunein/local
Sep 11 16:16:42 volumio volumio[862]: info: CoreCommandRouter::volumioReplaceandPlayItems
Sep 11 16:16:42 volumio volumio[862]: info: CoreStateMachine::ClearQueue
Sep 11 16:16:42 volumio volumio[862]: info: CoreStateMachine::stop
Sep 11 16:16:42 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 11 16:16:42 volumio volumio[862]: UNSET VOLATILE
Sep 11 16:16:42 volumio volumio[862]: info: CorePlayQueue::clearPlayQueue
Sep 11 16:16:42 volumio volumio[862]: info: CorePlayQueue::saveQueue
Sep 11 16:16:42 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:42 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue
Sep 11 16:16:42 volumio volumio[862]: info: CoreStateMachine::addQueueItems
Sep 11 16:16:42 volumio volumio[862]: info: CorePlayQueue::addQueueItems
Sep 11 16:16:42 volumio volumio[862]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s105824
Sep 11 16:16:42 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri
Sep 11 16:16:43 volumio volumio[862]: info: CorePlayQueue::saveQueue
Sep 11 16:16:43 volumio volumio[862]: info: CoreCommandRouter::volumioPushQueue
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::updateTrackBlock
Sep 11 16:16:43 volumio volumio[862]: info: CorePlayQueue::getTrackBlock
Sep 11 16:16:43 volumio volumio[862]: info: CoreCommandRouter::volumioPlay
Sep 11 16:16:43 volumio volumio[862]: UNSET VOLATILE
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::play index 0
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::stop
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 11 16:16:43 volumio volumio[862]: UNSET VOLATILE
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::play index undefined
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService undefined
Sep 11 16:16:43 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:16:43 volumio volumio[862]: info: CoreStateMachine::startPlaybackTimer
Sep 11 16:16:43 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:16:43 volumio volumio[862]: info: [1599841003384] ControllerWebradio::clearAddPlayTrack
Sep 11 16:16:43 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand stop
Sep 11 16:16:43 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand clear
Sep 11 16:16:43 volumio volumio[862]: info:
Sep 11 16:16:43 volumio volumio[862]: ---------------------------- MPD announces system playlist update
Sep 11 16:16:43 volumio volumio[862]: info: Ignoring MPD Status Update
Sep 11 16:16:43 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand load "http://stream.amazingradio.com:8000/stream.mp3"
Sep 11 16:16:43 volumio volumio[862]: error: updateQueue error: null
Sep 11 16:16:43 volumio volumio[862]: info: ------------------------------ 71ms
Sep 11 16:16:46 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand add "http://stream.amazingradio.com:8000/stream.mp3"
Sep 11 16:16:46 volumio volumio[862]: info:
Sep 11 16:16:46 volumio volumio[862]: ---------------------------- MPD announces system playlist update
Sep 11 16:16:46 volumio volumio[862]: info: Ignoring MPD Status Update
Sep 11 16:16:46 volumio volumio[862]: info: CoreStateMachine::setConsumeUpdateService mpd
Sep 11 16:16:46 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand play
Sep 11 16:16:46 volumio volumio[862]: info: ------------------------------ 61ms
Sep 11 16:16:46 volumio volumio[862]: info:
Sep 11 16:16:46 volumio volumio[862]: ---------------------------- MPD announces state update: player
Sep 11 16:16:46 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2
Sep 11 16:16:46 volumio volumio[862]: info: ControllerMpd::getState
Sep 11 16:16:46 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status
Sep 11 16:16:46 volumio volumio[862]: info:
Sep 11 16:16:46 volumio volumio[862]: ---------------------------- MPD announces state update: player
Sep 11 16:16:46 volumio volumio[862]: info: ControllerMpd::getState
Sep 11 16:16:46 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status
Sep 11 16:16:46 volumio volumio[862]: info:
Sep 11 16:16:47 volumio volumio[862]: ---------------------------- MPD announces system playlist update
Sep 11 16:16:47 volumio volumio[862]: info: Ignoring MPD Status Update
Sep 11 16:16:47 volumio volumio[862]: info:
Sep 11 16:16:47 volumio volumio[862]: ---------------------------- MPD announces state update: player
Sep 11 16:16:47 volumio volumio[862]: info: ControllerMpd::getState
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand status
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseState
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 11 16:16:47 volumio volumio[862]: info: ------------------------------ 116ms
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseState
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseState
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo
Sep 11 16:16:47 volumio volumio[862]: info: ControllerMpd::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::servicePushState
Sep 11 16:16:47 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:16:47 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":470,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Amazing Cast","artist":"Amazing Cast","album":null,"uri":"http://stream.amazingradio.com:8000/stream.mp3","trackType":"mp3"}
Sep 11 16:16:47 volumio volumio[862]: verbose: CURRENT POSITION 0
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   stateService play
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   currentStatus stop
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:47 volumio volumio[862]: info: ------------------------------ 452ms
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo
Sep 11 16:16:47 volumio volumio[862]: verbose: ControllerMpd::parseTrackInfo
Sep 11 16:16:47 volumio volumio[862]: info: ControllerMpd::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::servicePushState
Sep 11 16:16:47 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:16:47 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":493,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Amazing Cast","artist":"Amazing Cast","album":null,"uri":"http://stream.amazingradio.com:8000/stream.mp3","trackType":"mp3"}
Sep 11 16:16:47 volumio volumio[862]: verbose: CURRENT POSITION 0
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   stateService play
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   currentStatus play
Sep 11 16:16:47 volumio volumio[862]: info: Received an update from plugin. extracting info from payload
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:47 volumio volumio[862]: info: ControllerMpd::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::servicePushState
Sep 11 16:16:47 volumio volumio[862]: info: CorePlayQueue::getTrack 0
Sep 11 16:16:47 volumio volumio[862]: verbose: STATE SERVICE {"status":"play","position":0,"seek":586,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"112 Kbps","isStreaming":false,"title":"Amazing Cast","artist":"Amazing Cast","album":null,"uri":"http://stream.amazingradio.com:8000/stream.mp3","trackType":"mp3"}
Sep 11 16:16:47 volumio volumio[862]: verbose: CURRENT POSITION 0
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   stateService play
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::syncState   currentStatus play
Sep 11 16:16:47 volumio volumio[862]: info: Received an update from plugin. extracting info from payload
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:47 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:47 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 11 16:16:47 volumio volumio[862]: info: ------------------------------ 846ms
Sep 11 16:16:47 volumio volumio[862]: info: ------------------------------ 810ms
Sep 11 16:16:49 volumio volumio[862]: info: VolumeController::SetAlsaVolume30
Sep 11 16:16:49 volumio volumio[862]: info: CoreStateMachine::pushState
Sep 11 16:16:49 volumio volumio[862]: info: CoreCommandRouter::volumioPushState
Sep 11 16:16:49 volumio volumio[862]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo

Does that show anything useful??

Seems like the issue is due to a slow connection. We will refactor the code to handle that.

This shall handle it better, shall be fixed.

Ah brilliant :smiley:
What’s the best way to try it out? Can I swap that file out or does it become part of something else?

It;s probably not relevant but I doubt it’s my internet connection being slow as I get 72MBPS…

It will be available in a next release…