Can't play my music or my radio stations

[kjarrig]

Hi,

Strange thing happened to me, I was playing music from my NAS with Volumio 2, newest version. The day after, I can’t play music from my NAS or radio station that I’ve added. And can’t either play my playlist from Spotify, but I can play everything that comes installed with Volumio. I have another Raspberry PI with same version of Volumio, and it works as it should be.
I’ve rebooted a few times, unplugged the Raspberry and and plugged in again, no change.
If anyone has any idea what could be wrong I would be grateful if you would let me know.

And do you know what file keeps the radio station, don’t want to add it manually on my other PI.

Kindest regards,

Kjartan

[skikirkwood]

@kjarrig

Hi,

Strange thing happened to me, I was playing music from my NAS with Volumio 2, newest version. The day after, I can’t play music from my NAS or radio station that I’ve added. And can’t either play my playlist from Spotify, but I can play everything that comes installed with Volumio. I have another Raspberry PI with same version of Volumio, and it works as it should be.
I’ve rebooted a few times, unplugged the Raspberry and and plugged in again, no change.
If anyone has any idea what could be wrong I would be grateful if you would let me know.

And do you know what file keeps the radio station, don’t want to add it manually on my other PI.

Kindest regards,

Kjartan

When you add web radio stations to “My Web Radios” they are stored in /data/favourites/my-web-radio.

If you attach the contents of the log file /var/log/volumio.log that might help the diagnosis of what’s happening on your system.

[kjarrig]

Hi,
Can’t upload the logfile, get the error “The extension log is not allowed”, changed it to .txt, same result.
But here is the loga
2016-08-14T16:11:19.315Z - info: -------------------------------------------
2016-08-14T16:11:19.329Z - info: ----- Volumio2 ----
2016-08-14T16:11:19.330Z - info: -------------------------------------------
2016-08-14T16:11:19.330Z - info: ----- System startup ----
2016-08-14T16:11:19.331Z - info: -------------------------------------------
2016-08-14T16:11:19.815Z - info: Plugin folders cleanup
2016-08-14T16:11:19.816Z - info: Scanning into folder /volumio/app/plugins/
2016-08-14T16:11:19.818Z - info: Scanning category audio_interface
2016-08-14T16:11:19.824Z - info: Scanning category miscellanea
2016-08-14T16:11:19.827Z - info: Scanning category music_service
2016-08-14T16:11:19.829Z - info: Scanning category plugins.json
2016-08-14T16:11:19.830Z - info: Scanning category system_controller
2016-08-14T16:11:19.833Z - info: Scanning category user_interface
2016-08-14T16:11:19.835Z - info: Scanning into folder /data/plugins/
2016-08-14T16:11:19.838Z - info: Scanning category music_service
2016-08-14T16:11:19.840Z - info: Plugin folders cleanup completed
2016-08-14T16:11:19.844Z - info: Loading plugins from folder /volumio/app/plugins/
2016-08-14T16:11:19.859Z - info: Loading plugins from folder /data/plugins/
2016-08-14T16:11:19.866Z - info: Loading plugin “system”…
2016-08-14T16:11:19.965Z - info: Loading plugin “appearance”…
2016-08-14T16:11:20.657Z - info: Loading plugin “network”…
2016-08-14T16:11:20.793Z - info: Loading plugin “services”…
2016-08-14T16:11:20.804Z - info: Loading plugin “alsa_controller”…
2016-08-14T16:11:20.968Z - info: Loading plugin “volumio_command_line_client”…
2016-08-14T16:11:20.979Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:20.982Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:20.983Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:21.040Z - info: Loading plugin “volumiodiscovery”…
2016-08-14T16:11:21.173Z - info: Applying required configuration parameters for plugin volumiodiscovery
2016-08-14T16:11:21.259Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2016-08-14T16:11:21.261Z - info: Loading plugin “upnp”…
2016-08-14T16:11:21.276Z - info: [1471191081275] Starting Upmpd Daemon
2016-08-14T16:11:21.306Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2016-08-14T16:11:21.308Z - info: Loading plugin “airplay_emulation”…
2016-08-14T16:11:21.317Z - info: [1471191081316] Starting Shairport Sync
2016-08-14T16:11:21.323Z - info: Loading plugin “mpd”…
2016-08-14T16:11:21.435Z - info: Loading plugin “networkfs”…
2016-08-14T16:11:21.505Z - info: Loading plugin “alarm-clock”…
2016-08-14T16:11:21.802Z - info: Loading plugin “webradio”…
2016-08-14T16:11:23.144Z - info: Loading plugin “i2s_dacs”…
2016-08-14T16:11:23.165Z - info: I2S DAC not set, start Auto-detection on USB Bus
2016-08-14T16:11:23.188Z - info: Loading plugin “albumart”…
2016-08-14T16:11:23.220Z - info: Plugin example_plugin is not enabled
2016-08-14T16:11:23.222Z - info: Loading plugin “updater_comm”…
2016-08-14T16:11:23.679Z - info: Plugin mpdemulation is not enabled
2016-08-14T16:11:23.680Z - info: Loading plugin “websocket”…
2016-08-14T16:11:23.750Z - info: Loading plugin “spop”…
2016-08-14T16:11:25.154Z - info: Applying required configuration parameters for plugin spop
2016-08-14T16:11:25.170Z - info: ___________ START PLUGINS ___________
2016-08-14T16:11:25.182Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2016-08-14T16:11:25.185Z - info: [1471191085183] CoreMusicLibrary::Adding element Webradio
2016-08-14T16:11:25.236Z - info: Loading i18n strings for locale en
2016-08-14T16:11:25.337Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:25.338Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:25.339Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:25.340Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:25.341Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T16:11:25.365Z - info: BOOT COMPLETED
2016-08-14T16:11:25.491Z - info: [1471191085489] CoreStateMachine::resetVolumioState
2016-08-14T16:11:25.495Z - info: [1471191085493] CoreStateMachine::getcurrentVolume
2016-08-14T16:11:25.496Z - info: CoreCommandRouter::volumioRetrievevolume
2016-08-14T16:11:25.528Z - info: [1471191085527] CoreStateMachine::updateTrackBlock
2016-08-14T16:11:25.534Z - info: [1471191085532] CorePlayQueue::getTrackBlock
2016-08-14T16:11:25.768Z - info: SpopD Daemon Started
2016-08-14T16:11:25.845Z - info: MPD running with PID599 ,establishing connection
2016-08-14T16:11:25.881Z - info: Setting Device type: Raspberry PI
2016-08-14T16:11:26.132Z - info: [1471191086130] VolumeController:: Volume=13 Mute =false
2016-08-14T16:11:26.139Z - info: [1471191086137] CoreStateMachine::pushState
2016-08-14T16:11:26.142Z - info: [1471191086140] CoreStateMachine::getState
2016-08-14T16:11:26.144Z - info: [1471191086143] CorePlayQueue::getTrack 0
2016-08-14T16:11:26.146Z - info: CoreCommandRouter::volumioPushState
2016-08-14T16:11:26.147Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2016-08-14T16:11:26.153Z - info: [1471191086152] InterfaceWebUI::pushState
2016-08-14T16:11:26.188Z - info: Pushing Favourites {“service”:“mpd”,“uri”:"",“favourite”:false}
2016-08-14T16:11:26.320Z - info: Reloading queue from file
2016-08-14T16:11:26.456Z - info: Setting volume on startup at 30
2016-08-14T16:11:26.509Z - info:
2016-08-14T16:11:26.531Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2016-08-14T16:11:26.563Z - info:
[1471191086562] ---------------------------- Client requests Volume 30
2016-08-14T16:11:26.573Z - info: [1471191086572] VolumeController::SetAlsaVolume30
2016-08-14T16:11:26.597Z - info: [1471191086595] ------------------------------ 33ms
2016-08-14T16:11:26.635Z - info: CoreCommandRouter::volumioGetState
2016-08-14T16:11:26.636Z - info: [1471191086635] CoreStateMachine::getState
2016-08-14T16:11:26.637Z - info: [1471191086636] CorePlayQueue::getTrack 0
2016-08-14T16:11:26.640Z - info:
[1471191086638] ---------------------------- Client requests Volumio state
2016-08-14T16:11:26.645Z - info: [1471191086644] Listing playlists
2016-08-14T16:11:26.651Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2016-08-14T16:11:26.655Z - info:
[1471191086654] ---------------------------- Client requests Menu Items
2016-08-14T16:11:26.666Z - info: CoreCommandRouter::volumioGetBrowseSources
2016-08-14T16:11:26.670Z - info: CoreCommandRouter::volumioGetQueue
2016-08-14T16:11:26.673Z - info: [1471191086671] CoreStateMachine::getQueue
2016-08-14T16:11:26.675Z - info: [1471191086674] CorePlayQueue::getQueue
2016-08-14T16:11:26.678Z - info:
[1471191086676] ---------------------------- Client requests Volumio queue
2016-08-14T16:11:26.682Z - info: [1471191086682] InterfaceWebUI::pushState
2016-08-14T16:11:26.692Z - info: [1471191086691] InterfaceWebUI::pushQueue
2016-08-14T16:11:26.694Z - info: [{“uri”:“mms://3.efstaleiti.rs.ruv.is/ras2.ruv.is”,“title”:“Rás-2”,“service”:“webradio”,“name”:“Rás-2”,“albumart”:"/albumart",“samplerate”:"",“bitdepth”:"",“channels”:0,“trackType”:“webradio”}]
2016-08-14T16:11:26.698Z - info: [1471191086697] ------------------------------ 64ms
2016-08-14T16:11:26.699Z - info: [1471191086699] ------------------------------ 30ms
2016-08-14T16:11:26.778Z - info: [1471191086776] VolumeController::Volume 100
2016-08-14T16:11:26.780Z - info: [1471191086779] CoreStateMachine::pushState
2016-08-14T16:11:26.781Z - info: [1471191086780] CoreStateMachine::getState
2016-08-14T16:11:26.782Z - info: [1471191086781] CorePlayQueue::getTrack 0
2016-08-14T16:11:26.784Z - info: CoreCommandRouter::volumioPushState
2016-08-14T16:11:26.785Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2016-08-14T16:11:26.789Z - info: [1471191086788] InterfaceWebUI::pushState
2016-08-14T16:11:26.864Z - info: Pushing Favourites {“service”:“webradio”,“uri”:“mms://3.efstaleiti.rs.ruv.is/ras2.ruv.is”,“favourite”:false}
2016-08-14T16:11:26.888Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2016-08-14T16:11:26.951Z - info: mDNS: Found device Volumio
2016-08-14T16:11:27.074Z - info: CoreCommandRouter::volumioGetState
2016-08-14T16:11:27.081Z - info: [1471191087079] CoreStateMachine::getState
2016-08-14T16:11:27.084Z - info: [1471191087082] CorePlayQueue::getTrack 0
2016-08-14T16:11:27.087Z - info:
[1471191087086] ---------------------------- Client requests Volumio state
2016-08-14T16:11:27.093Z - info: [1471191087092] Listing playlists
2016-08-14T16:11:27.100Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2016-08-14T16:11:27.102Z - info:
[1471191087101] ---------------------------- Client requests Menu Items
2016-08-14T16:11:27.107Z - info: [1471191087106] InterfaceWebUI::pushState
2016-08-14T16:11:27.115Z - info: [1471191087114] ------------------------------ 48ms
2016-08-14T16:11:27.118Z - info: [1471191087117] Shairport-Sync Started
2016-08-14T16:11:27.143Z - info: [1471191087143] ------------------------------ 489ms
2016-08-14T16:11:27.154Z - info: Upmpdcli Daemon Started
2016-08-14T16:11:27.276Z - info: [1471191087275] ------------------------------ 174ms
2016-08-14T16:11:27.303Z - info: Volumio called home
2016-08-14T16:11:30.821Z - info: Connecting to daemon
2016-08-14T16:11:31.021Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2016-08-14T16:11:31.023Z - info: [1471191091022] CoreMusicLibrary::Adding element Spotify
2016-08-14T16:11:31.032Z - info: FIRST BRANCH
2016-08-14T16:11:31.698Z - info: Spotify access token expires at 1471194690856
2016-08-14T16:11:31.699Z - info: Spotify access token is BQCi_Nat4QW8N_tFtaCXJ7UcG9L7JTIQaLzuVhLithVm0H4pJYord3dgkvOA1u3bjG1sNR8JtdV1-I1wjQNoVg
2016-08-14T16:11:31.701Z - info: Spotify credentials grant success
2016-08-14T16:12:07.028Z - info: mDNS: Found device Volumio-2
2016-08-14T16:12:32.942Z - info: CoreCommandRouter::volumioGetBrowseSources
2016-08-14T16:12:32.954Z - info: CoreCommandRouter::volumioGetQueue
2016-08-14T16:12:32.958Z - info: [1471191152955] CoreStateMachine::getQueue
2016-08-14T16:12:32.960Z - info: [1471191152958] CorePlayQueue::getQueue
2016-08-14T16:12:32.964Z - info:
[1471191152961] ---------------------------- Client requests Volumio queue
2016-08-14T16:12:32.966Z - info: [1471191152966] InterfaceWebUI::pushQueue
2016-08-14T16:12:32.967Z - info: [{“uri”:“mms://3.efstaleiti.rs.ruv.is/ras2.ruv.is”,“title”:“Rás-2”,“service”:“webradio”,“name”:“Rás-2”,“albumart”:"/albumart",“samplerate”:"",“bitdepth”:"",“channels”:0,“trackType”:“webradio”}]
2016-08-14T16:12:32.971Z - info: [1471191152970] ------------------------------ 23ms
2016-08-14T16:12:34.389Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T16:12:35.179Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T16:12:35.867Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T16:12:37.923Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T16:12:39.480Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T16:12:42.174Z - info: CoreCommandRouter::volumioReplaceandPlayItems
2016-08-14T16:12:42.178Z - info: [1471191162175] CoreStateMachine::ClearQueue
2016-08-14T16:12:42.182Z - info: [1471191162179] CoreStateMachine::stop
2016-08-14T16:12:42.187Z - info: [1471191162183] CoreStateMachine::setConsumeUpdateService undefined
2016-08-14T16:12:42.191Z - info: [1471191162188] CorePlayQueue::clearPlayQueue
2016-08-14T16:12:42.196Z - info: [1471191162192] CorePlayQueue::saveQueue
2016-08-14T16:12:42.202Z - info: CoreCommandRouter::volumioPushQueue
2016-08-14T16:12:42.208Z - info: [1471191162204] InterfaceWebUI::pushQueue
2016-08-14T16:12:42.211Z - info: []
2016-08-14T16:12:42.214Z - info: [1471191162212] CoreStateMachine::addQueueItems
2016-08-14T16:12:42.219Z - info: [1471191162218] CorePlayQueue::addQueueItems
2016-08-14T16:12:42.223Z - info: uri=music-library/NAS/Tónlist/a-Ha/Scoundrel Days/01 Scoundrel Days.flac, title=Scoundrel Days, service=mpd
2016-08-14T16:12:42.226Z - info: First index is 0
2016-08-14T16:12:42.228Z - info: ADDING THIS ITEM TO QUEUE: {“uri”:“music-library/NAS/Tónlist/a-Ha/Scoundrel Days/01 Scoundrel Days.flac”,“title”:“Scoundrel Days”,“service”:“mpd”}
2016-08-14T16:12:42.230Z - info: Exploding uri music-library/NAS/Tónlist/a-Ha/Scoundrel Days/01 Scoundrel Days.flac in service mpd
2016-08-14T16:12:42.235Z - info: ----------------------------/mnt/NAS/Tónlist/a-Ha/Scoundrel Days/01 Scoundrel Days.flac

Thank you for your help.

Kjartan

[skikirkwood]

I reproduced the state you were in, but still not sure what caused it. One thing I am suspicious of is the radio station:

mms://3.efstaleiti.rs.ruv.is/ras2.ruv.is

I added that to my Web Radios and couldn’t play anything after it, perhaps the mms:// is triggering some bug.

To get back to a working state try first to delete the above radio station from your My Web Radio, then hit the bottom right Queue tab, hit the delete icon on the top right, and reboot your machine.

Update: Just verified that the above web radio URL does indeed screw up the state of Volumio for me and I can’t play anything afterwards. Deleting the queue and rebooting fixed it. I’ll file a bug report.

[kjarrig]

Tried you suggestion, deleted from My Radio, and it was also in Fav radios, and deleted there too. Clear the queu, rebooted, same result.

2016-08-14T21:10:15.579Z - info: -------------------------------------------
2016-08-14T21:10:15.592Z - info: ----- Volumio2 ----
2016-08-14T21:10:15.593Z - info: -------------------------------------------
2016-08-14T21:10:15.594Z - info: ----- System startup ----
2016-08-14T21:10:15.595Z - info: -------------------------------------------
2016-08-14T21:10:16.058Z - info: Plugin folders cleanup
2016-08-14T21:10:16.059Z - info: Scanning into folder /volumio/app/plugins/
2016-08-14T21:10:16.061Z - info: Scanning category audio_interface
2016-08-14T21:10:16.063Z - info: Scanning category miscellanea
2016-08-14T21:10:16.066Z - info: Scanning category music_service
2016-08-14T21:10:16.068Z - info: Scanning category plugins.json
2016-08-14T21:10:16.069Z - info: Scanning category system_controller
2016-08-14T21:10:16.072Z - info: Scanning category user_interface
2016-08-14T21:10:16.074Z - info: Scanning into folder /data/plugins/
2016-08-14T21:10:16.077Z - info: Scanning category music_service
2016-08-14T21:10:16.078Z - info: Plugin folders cleanup completed
2016-08-14T21:10:16.082Z - info: Loading plugins from folder /volumio/app/plugins/
2016-08-14T21:10:16.097Z - info: Loading plugins from folder /data/plugins/
2016-08-14T21:10:16.105Z - info: Loading plugin “system”…
2016-08-14T21:10:16.206Z - info: Loading plugin “appearance”…
2016-08-14T21:10:16.865Z - info: Loading plugin “network”…
2016-08-14T21:18:46.465Z - info: Loading plugin “services”…
2016-08-14T21:18:46.478Z - info: Loading plugin “alsa_controller”…
2016-08-14T21:18:46.662Z - info: Loading plugin “volumio_command_line_client”…
2016-08-14T21:18:46.675Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:46.680Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:46.682Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:46.742Z - info: Loading plugin “volumiodiscovery”…
2016-08-14T21:18:46.862Z - info: Applying required configuration parameters for plugin volumiodiscovery
2016-08-14T21:18:46.945Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2016-08-14T21:18:46.948Z - info: Loading plugin “upnp”…
2016-08-14T21:18:46.962Z - info: [1471209526961] Starting Upmpd Daemon
2016-08-14T21:18:46.992Z - info: CoreCommandRouter::executeOnPlugin: system , registerCallback
2016-08-14T21:18:46.994Z - info: Loading plugin “airplay_emulation”…
2016-08-14T21:18:47.002Z - info: [1471209527001] Starting Shairport Sync
2016-08-14T21:18:47.008Z - info: Loading plugin “mpd”…
2016-08-14T21:18:47.119Z - info: Loading plugin “networkfs”…
2016-08-14T21:18:47.193Z - info: Loading plugin “alarm-clock”…
2016-08-14T21:18:47.489Z - info: Loading plugin “webradio”…
2016-08-14T21:18:48.810Z - info: Loading plugin “i2s_dacs”…
2016-08-14T21:18:48.832Z - info: I2S DAC not set, start Auto-detection on USB Bus
2016-08-14T21:18:48.854Z - info: Loading plugin “albumart”…
2016-08-14T21:18:48.887Z - info: Plugin example_plugin is not enabled
2016-08-14T21:18:48.889Z - info: Loading plugin “updater_comm”…
2016-08-14T21:18:49.340Z - info: Plugin mpdemulation is not enabled
2016-08-14T21:18:49.342Z - info: Loading plugin “websocket”…
2016-08-14T21:18:49.412Z - info: Loading plugin “spop”…
2016-08-14T21:18:50.824Z - info: Applying required configuration parameters for plugin spop
2016-08-14T21:18:50.841Z - info: ___________ START PLUGINS ___________
2016-08-14T21:18:50.855Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2016-08-14T21:18:50.860Z - info: [1471209530857] CoreMusicLibrary::Adding element Webradio
2016-08-14T21:18:50.914Z - info: Loading i18n strings for locale en
2016-08-14T21:18:51.036Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:51.037Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:51.039Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:51.039Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:51.040Z - info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
2016-08-14T21:18:51.065Z - info: BOOT COMPLETED
2016-08-14T21:18:51.160Z - info: [1471209531159] CoreStateMachine::resetVolumioState
2016-08-14T21:18:51.163Z - info: [1471209531162] CoreStateMachine::getcurrentVolume
2016-08-14T21:18:51.165Z - info: CoreCommandRouter::volumioRetrievevolume
2016-08-14T21:18:51.205Z - info: [1471209531203] CoreStateMachine::updateTrackBlock
2016-08-14T21:18:51.208Z - info: [1471209531207] CorePlayQueue::getTrackBlock
2016-08-14T21:18:51.444Z - info: SpopD Daemon Started
2016-08-14T21:18:51.532Z - info: MPD running with PID598 ,establishing connection
2016-08-14T21:18:51.581Z - info: Setting Device type: Raspberry PI
2016-08-14T21:18:51.988Z - info: Reloading queue from file
2016-08-14T21:18:52.044Z - info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
2016-08-14T21:18:52.150Z - info: Setting volume on startup at 30
2016-08-14T21:18:52.236Z - info: Volumio called home
2016-08-14T21:18:52.240Z - info: [1471209532238] VolumeController:: Volume=28 Mute =false
2016-08-14T21:18:52.246Z - info: [1471209532245] CoreStateMachine::pushState
2016-08-14T21:18:52.249Z - info: [1471209532248] CoreStateMachine::getState
2016-08-14T21:18:52.256Z - info: [1471209532251] CorePlayQueue::getTrack 0
2016-08-14T21:18:52.259Z - info: CoreCommandRouter::volumioPushState
2016-08-14T21:18:52.260Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2016-08-14T21:18:52.266Z - info: [1471209532265] InterfaceWebUI::pushState
2016-08-14T21:18:52.296Z - info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:5V14qgGsdjpCAFl989OR8D”,“favourite”:false}
2016-08-14T21:18:52.323Z - info: CoreCommandRouter::volumioGetState
2016-08-14T21:18:52.324Z - info: [1471209532323] CoreStateMachine::getState
2016-08-14T21:18:52.325Z - info: [1471209532324] CorePlayQueue::getTrack 0
2016-08-14T21:18:52.328Z - info:
[1471209532327] ---------------------------- Client requests Volumio state
2016-08-14T21:18:52.336Z - info: [1471209532335] Listing playlists
2016-08-14T21:18:52.341Z - info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
2016-08-14T21:18:52.345Z - info:
[1471209532344] ---------------------------- Client requests Menu Items
2016-08-14T21:18:52.349Z - info: [1471209532348] InterfaceWebUI::pushState
2016-08-14T21:18:52.363Z - info: [1471209532361] ------------------------------ 39ms
2016-08-14T21:18:52.370Z - info:
2016-08-14T21:18:52.458Z - info:
[1471209532457] ---------------------------- Client requests Volume 30
2016-08-14T21:18:52.463Z - info: [1471209532462] VolumeController::SetAlsaVolume30
2016-08-14T21:18:52.485Z - info: [1471209532484] ------------------------------ 27ms
2016-08-14T21:18:52.593Z - info: [1471209532591] VolumeController::Volume 100
2016-08-14T21:18:52.596Z - info: [1471209532595] CoreStateMachine::pushState
2016-08-14T21:18:52.600Z - info: [1471209532597] CoreStateMachine::getState
2016-08-14T21:18:52.601Z - info: [1471209532600] CorePlayQueue::getTrack 0
2016-08-14T21:18:52.602Z - info: CoreCommandRouter::volumioPushState
2016-08-14T21:18:52.604Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2016-08-14T21:18:52.607Z - info: [1471209532606] InterfaceWebUI::pushState
2016-08-14T21:18:52.656Z - info: mDNS: Found device Volumio-2
2016-08-14T21:18:52.760Z - info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:5V14qgGsdjpCAFl989OR8D”,“favourite”:false}
2016-08-14T21:18:52.914Z - info: [1471209532913] ------------------------------ 569ms
2016-08-14T21:18:52.916Z - info: mDNS: Found device Volumio
2016-08-14T21:18:52.976Z - info: Upmpdcli Daemon Started
2016-08-14T21:18:52.978Z - info: [1471209532977] Shairport-Sync Started
2016-08-14T21:18:56.455Z - info: Connecting to daemon
2016-08-14T21:18:56.686Z - info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
2016-08-14T21:18:56.693Z - info: [1471209536687] CoreMusicLibrary::Adding element Spotify
2016-08-14T21:18:56.705Z - info: FIRST BRANCH
2016-08-14T21:18:57.503Z - info: Spotify access token expires at 1471213136492
2016-08-14T21:18:57.517Z - info: Spotify access token is BQDWLNfX2Q4SOAylPv-YnUzACYNo8DganR5ilXEmJBaoDZ3ppYt4cjmqJwjA3ipkCheBAMakgnQvaSH4pPgYQw
2016-08-14T21:18:57.519Z - info: Spotify credentials grant success
2016-08-14T21:19:06.202Z - info:
[1471209546201] ---------------------------- Client requests Volume 32
2016-08-14T21:19:06.205Z - info: [1471209546204] VolumeController::SetAlsaVolume32
2016-08-14T21:19:06.230Z - info: [1471209546229] ------------------------------ 28ms
2016-08-14T21:19:06.251Z - info: [1471209546250] VolumeController::Volume 32
2016-08-14T21:19:06.252Z - info: [1471209546252] CoreStateMachine::pushState
2016-08-14T21:19:06.253Z - info: [1471209546253] CoreStateMachine::getState
2016-08-14T21:19:06.254Z - info: [1471209546254] CorePlayQueue::getTrack 0
2016-08-14T21:19:06.255Z - info: CoreCommandRouter::volumioPushState
2016-08-14T21:19:06.256Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2016-08-14T21:19:06.259Z - info: [1471209546258] InterfaceWebUI::pushState
2016-08-14T21:19:06.287Z - info: Pushing Favourites {“service”:“spop”,“uri”:“spotify:track:5V14qgGsdjpCAFl989OR8D”,“favourite”:false}
2016-08-14T21:19:06.804Z - info: CoreCommandRouter::volumioGetBrowseSources
2016-08-14T21:19:06.813Z - info: CoreCommandRouter::volumioGetQueue
2016-08-14T21:19:06.815Z - info: [1471209546814] CoreStateMachine::getQueue
2016-08-14T21:19:06.817Z - info: [1471209546816] CorePlayQueue::getQueue
2016-08-14T21:19:06.818Z - info:
[1471209546817] ---------------------------- Client requests Volumio queue
2016-08-14T21:19:06.822Z - info: [1471209546819] InterfaceWebUI::pushQueue
2016-08-14T21:19:06.824Z - info: [{“uri”:“spotify:track:5V14qgGsdjpCAFl989OR8D”,“service”:“spop”,“name”:“Dark Chest Of Wonders”,“artist”:“Nightwish”,“album”:“End Of An Era”,“type”:“track”,“duration”:308,“tracknumber”:1,“albumart”:“https://i.scdn.co/image/6d767de7c1e81937f688a0cf54ef7ade3cd121d1",“samplerate”:“320Kbps”,“bitdepth”:"16 bit”,“trackType”:“spotify”}]
2016-08-14T21:19:06.837Z - info: [1471209546837] ------------------------------ 25ms
2016-08-14T21:19:08.581Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T21:19:09.223Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T21:19:09.843Z - info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
2016-08-14T21:19:15.066Z - info: CoreCommandRouter::volumioReplaceandPlayItems
2016-08-14T21:19:15.071Z - info: [1471209555067] CoreStateMachine::ClearQueue
2016-08-14T21:19:15.073Z - info: [1471209555072] CoreStateMachine::stop
2016-08-14T21:19:15.075Z - info: [1471209555074] CoreStateMachine::setConsumeUpdateService undefined
2016-08-14T21:19:15.078Z - info: [1471209555077] CorePlayQueue::clearPlayQueue
2016-08-14T21:19:15.080Z - info: [1471209555079] CorePlayQueue::saveQueue
2016-08-14T21:19:15.083Z - info: CoreCommandRouter::volumioPushQueue
2016-08-14T21:19:15.086Z - info: [1471209555085] InterfaceWebUI::pushQueue
2016-08-14T21:19:15.087Z - info: []
2016-08-14T21:19:15.089Z - info: [1471209555088] CoreStateMachine::addQueueItems
2016-08-14T21:19:15.093Z - info: [1471209555092] CorePlayQueue::addQueueItems
2016-08-14T21:19:15.098Z - info: uri=music-library/NAS/Tónlist/a-Ha, title=a-Ha, service=null
2016-08-14T21:19:15.100Z - info: First index is 0
2016-08-14T21:19:15.101Z - info: ADDING THIS ITEM TO QUEUE: {“uri”:“music-library/NAS/Tónlist/a-Ha”,“title”:“a-Ha”,“service”:null}
2016-08-14T21:19:15.103Z - info: Exploding uri music-library/NAS/Tónlist/a-Ha in service mpd
2016-08-14T21:19:15.111Z - info: ----------------------------/mnt/NAS/Tónlist/a-Ha

Nothing starts playing.

[skikirkwood]

I’m out of ideas, hopefully a more experienced person can help you here!

[skikirkwood]

@skikirkwood

I’m out of ideas, hopefully a more experienced person can help you here!

Ok, one more idea. On the Pi do a:

mpc status

Normally you will get back something that looks like this:

Vocal Lounge - Digitally Imported Premium: Naomi - White
[playing] #1/1 4:03/0:00 (0%)
volume: 77% repeat: off random: off single: off consume: off

If the command doesn’t return at all, find the pid of the mpd process:

ps -ef | grep mpd

Then kill it:

sudo kill -9

mpd will automatically restart. See if that helps. If not, I’m then totally out of ideas.

[kjarrig]

This doesn’t work either. When I try to play a song from the NAS, it doesn’t start. I select the home button in Volumio, click on Music Library, nothing is there, I refresh the browser, select the Music Library, and the NAS reappear. So it looks like a reinstall.
And one bug, the Default Startup Volume, I have it at 30, but when I Reboot Volumio, the Startup Volume is 100.

Kindest regards,

Kjartan

[kjarrig]

Now this happens to my again. After using the URL mms://3.efstaleiti.rs.ruv.is/ras2.ruv.is for a few months now, and it have been working fine, suddenly it stops working. Same behavior as before, I click on the station, nothing happens, no artist appear in my artist list, reboot, and see my artist list, and can play it. I click again on my web radio, and the artist disappear and I can’t play anything. Not “anything” as I can “play” songs from Spotify, the song starts but just noise.
As this is my wives favorites radio station I’m in a trouble

Any help is appreciated.

I’m on Volumio 2.413.

Kjartan