search

volumio / Volumio2

Volumio 2 - Audiophile Music Player
http://volumio.org
Other
1.37k stars 315 forks source link

If webradio is playing, next and prev button doesn't work properly #1122

Closed ghost closed 4 years ago

ghost commented 7 years ago

If webradio isplaying pressing next or prev button the next or prev song in the queue is not played but playing remain on current webradio

ggolec commented 7 years ago

I'm not quite sure if this happens only by Webradio but I observed the same after couple of days of running volumio. It happens on webradion as well as music from NAS over nfs/smb. When click to next it does nothing. You have to click Next twice then second click does action. It is the same when you have playlist. When click only once nothing happens, you must clich twice to play selected track.

It is important that it happens after few days... Maybe there is some trigger that cause this wierd behavior. Just after restart everything works as a charm

Volumio2 on rasp3 vanilla install of 2.129

ghost commented 7 years ago

IN my case it happens only with WebRadio from the beginning

kregory commented 7 years ago

only with webradio too for me

RandolfEbelt commented 7 years ago

I also have this issue

xipmix commented 7 years ago

I can confirm this on 2.246, rpi2. To reproduce

edit: I was tailing /var/log/volumio.log while doing these tests, not running journalctl -f

2017-08-30T13:06:08.931Z - info: 
[1504098368925] ---------------------------- Client requests Volumio play
2017-08-30T13:06:08.934Z - info: CoreCommandRouter::volumioPlay
2017-08-30T13:06:08.936Z - info: [1504098368935] CoreStateMachine::play index undefined
2017-08-30T13:06:08.937Z - info: [1504098368936] CoreStateMachine::setConsumeUpdateService undefined
2017-08-30T13:06:08.938Z - info: [1504098368937] CorePlayQueue::getTrack 0
2017-08-30T13:06:08.939Z - info: [1504098368939] CoreStateMachine::startPlaybackTimer
2017-08-30T13:06:08.940Z - info: [1504098368940] CorePlayQueue::getTrack 0
2017-08-30T13:06:08.953Z - info: [1504098368952] ControllerMpd::resume
2017-08-30T13:06:08.955Z - info: [1504098368954] ControllerMpd::sendMpdCommand play
2017-08-30T13:06:08.965Z - info: [1504098368962] sending command...
2017-08-30T13:06:08.976Z - info: [1504098368972] ------------------------------ 47ms
2017-08-30T13:06:08.985Z - info: Mpd Status Update: player
2017-08-30T13:06:08.990Z - info: 
[1504098368985] ---------------------------- MPD announces state update
2017-08-30T13:06:08.995Z - info: [1504098368991] parsing response...
2017-08-30T13:06:08.998Z - info: [1504098368995] ControllerMpd::getState
2017-08-30T13:06:09.002Z - info: [1504098368999] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:09.003Z - info: [1504098369002] sending command...
2017-08-30T13:06:09.008Z - info: [1504098369007] parsing response...
2017-08-30T13:06:09.010Z - info: [1504098369009] ControllerMpd::parseState
2017-08-30T13:06:09.011Z - info: [1504098369010] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:09.013Z - info: [1504098369012] sending command...
2017-08-30T13:06:09.019Z - info: [1504098369018] parsing response...
2017-08-30T13:06:09.022Z - info: [1504098369021] ControllerMpd::parseTrackInfo
2017-08-30T13:06:09.024Z - info: [1504098369023] ControllerMpd::pushState
2017-08-30T13:06:09.025Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:09.026Z - info: [1504098369025] CoreStateMachine::syncState
2017-08-30T13:06:09.027Z - info: [1504098369026] CorePlayQueue::getTrack 0
2017-08-30T13:06:09.028Z - info: STATE SERVICE {"status":"play","position":0,"seek":9450,"duration":179,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Desire","artist":"U2","album":"Rattle and Hum","uri":"NAS/testu2/Rattle_and_Hum/03-Desire.flac","trackType":"flac"}
2017-08-30T13:06:09.029Z - info: CURRENT POSITION 0
2017-08-30T13:06:09.030Z - info: [1504098369030] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:09.031Z - info: [1504098369031] CoreStateMachine::syncState   currentStatus pause
2017-08-30T13:06:09.033Z - info: [1504098369032] CoreStateMachine::pushState
2017-08-30T13:06:09.034Z - info: [1504098369033] CoreStateMachine::getState
2017-08-30T13:06:09.035Z - info: [1504098369034] CorePlayQueue::getTrack 0
2017-08-30T13:06:09.036Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:09.037Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:09.039Z - info: [1504098369038] interfaceApi::pushState
2017-08-30T13:06:09.041Z - info: [1504098369040] InterfaceWebUI::pushState
2017-08-30T13:06:09.082Z - info: [1504098369080] CoreStateMachine::pushState
2017-08-30T13:06:09.083Z - info: [1504098369082] CoreStateMachine::getState
2017-08-30T13:06:09.085Z - info: [1504098369084] CorePlayQueue::getTrack 0
2017-08-30T13:06:09.086Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:09.088Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:09.091Z - info: [1504098369090] interfaceApi::pushState
2017-08-30T13:06:09.093Z - info: [1504098369092] InterfaceWebUI::pushState
2017-08-30T13:06:09.142Z - info: [1504098369140] ------------------------------ 160ms
2017-08-30T13:06:09.154Z - info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/03-Desire.flac","favourite":false}
2017-08-30T13:06:09.161Z - info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/03-Desire.flac","favourite":false}

<press next track>
2017-08-30T13:06:36.531Z - info: 
[1504098396525] ---------------------------- Client requests Volumio next
2017-08-30T13:06:36.537Z - info: CoreCommandRouter::volumioNext
2017-08-30T13:06:36.543Z - info: [1504098396539] CoreStateMachine::next
2017-08-30T13:06:36.558Z - info: [1504098396543] CoreStateMachine::stop
2017-08-30T13:06:36.563Z - info: [1504098396558] CoreStateMachine::setConsumeUpdateService undefined
2017-08-30T13:06:36.571Z - info: [1504098396567] CoreStateMachine::stPlaybackTimer
2017-08-30T13:06:36.576Z - info: [1504098396571] CoreStateMachine::updateTrackBlock
2017-08-30T13:06:36.580Z - info: [1504098396576] CorePlayQueue::getTrackBlock
2017-08-30T13:06:36.585Z - info: [1504098396581] CoreStateMachine::pushState
2017-08-30T13:06:36.595Z - info: [1504098396585] CoreStateMachine::getState
2017-08-30T13:06:36.599Z - info: [1504098396595] CorePlayQueue::getTrack 0
2017-08-30T13:06:36.603Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:36.612Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:36.617Z - info: [1504098396613] interfaceApi::pushState
2017-08-30T13:06:36.621Z - info: [1504098396618] InterfaceWebUI::pushState
2017-08-30T13:06:36.678Z - info: [1504098396674] CoreStateMachine::serviceStop
2017-08-30T13:06:36.682Z - info: [1504098396679] CorePlayQueue::getTrack 0
2017-08-30T13:06:36.684Z - info: CoreCommandRouter::serviceStop
2017-08-30T13:06:36.687Z - info: [1504098396685] ControllerMpd::stop
2017-08-30T13:06:36.688Z - info: [1504098396687] ControllerMpd::sendMpdCommand stop
2017-08-30T13:06:36.696Z - info: [1504098396695] sending command...
2017-08-30T13:06:36.699Z - info: [1504098396698] ------------------------------ 173ms
2017-08-30T13:06:36.715Z - info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/03-Desire.flac","favourite":false}
2017-08-30T13:06:36.746Z - info: [1504098396745] parsing response...
2017-08-30T13:06:36.751Z - info: [1504098396750] CoreStateMachine::play index undefined
2017-08-30T13:06:36.753Z - info: [1504098396752] CoreStateMachine::setConsumeUpdateService undefined
2017-08-30T13:06:36.754Z - info: [1504098396753] CorePlayQueue::getTrack 1
2017-08-30T13:06:36.756Z - info: [1504098396755] CoreStateMachine::startPlaybackTimer
2017-08-30T13:06:36.757Z - info: [1504098396756] CorePlayQueue::getTrack 1
2017-08-30T13:06:36.763Z - info: [1504098396761] ControllerWebradio::clearAddPlayTrack
2017-08-30T13:06:36.764Z - info: [1504098396763] ControllerMpd::sendMpdCommand stop
2017-08-30T13:06:36.771Z - info: [1504098396770] sending command...
2017-08-30T13:06:36.774Z - info: [1504098396773] CoreStateMachine::updateTrackBlock
2017-08-30T13:06:36.775Z - info: [1504098396774] CorePlayQueue::getTrackBlock
2017-08-30T13:06:36.779Z - info: Mpd Status Update: player
2017-08-30T13:06:36.780Z - info: 
[1504098396779] ---------------------------- MPD announces state update
2017-08-30T13:06:36.782Z - info: [1504098396781] ControllerMpd::getState
2017-08-30T13:06:36.783Z - info: [1504098396783] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:36.785Z - info: [1504098396784] parsing response...
2017-08-30T13:06:36.786Z - info: [1504098396785] sending command...
2017-08-30T13:06:36.789Z - info: [1504098396788] ControllerMpd::sendMpdCommand clear
2017-08-30T13:06:36.791Z - info: [1504098396790] sending command...
2017-08-30T13:06:36.799Z - info: 
[1504098396798] ---------------------------- MPD announces system state update
2017-08-30T13:06:36.800Z - info: Mpd Status Update: playlist
2017-08-30T13:06:36.802Z - info: 
[1504098396801] ---------------------------- MPD announces state update
2017-08-30T13:06:36.803Z - info: [1504098396802] parsing response...
2017-08-30T13:06:36.805Z - info: [1504098396804] parsing response...
2017-08-30T13:06:36.807Z - info: [1504098396806] ControllerMpd::getState
2017-08-30T13:06:36.808Z - info: [1504098396807] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:36.809Z - info: [1504098396808] ControllerMpd::parseState
2017-08-30T13:06:36.811Z - info: [1504098396810] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:36.813Z - info: [1504098396812] ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=709809"
2017-08-30T13:06:36.816Z - info: [1504098396815] sending command...
2017-08-30T13:06:36.818Z - info: [1504098396817] sending command...
2017-08-30T13:06:36.820Z - info: [1504098396819] sending command...
2017-08-30T13:06:36.825Z - info: 
2017-08-30T13:06:36.827Z - info: [1504098396826] ------------------------------ 28ms
2017-08-30T13:06:36.828Z - info: [1504098396827] parsing response...
2017-08-30T13:06:36.830Z - info: [1504098396829] parsing response...
2017-08-30T13:06:36.833Z - info: [1504098396830] ControllerMpd::parseState
2017-08-30T13:06:36.835Z - info: [1504098396834] ControllerMpd::parseTrackInfo
2017-08-30T13:06:36.841Z - info: [1504098396839] ControllerMpd::pushState
2017-08-30T13:06:36.842Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:36.844Z - info: [1504098396843] CoreStateMachine::syncState
2017-08-30T13:06:36.845Z - info: [1504098396844] CorePlayQueue::getTrack 1
2017-08-30T13:06:36.847Z - info: [1504098396846] Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
2017-08-30T13:06:36.849Z - info: [1504098396848] ControllerMpd::pushError
2017-08-30T13:06:36.865Z - info:  TypeError: Cannot read property 'split' of undefined
    at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:289:53)
    at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
    at _combinedTickCallback (internal/process/next_tick.js:73:7)
    at process._tickCallback (internal/process/next_tick.js:104:9)
2017-08-30T13:06:36.867Z - info: [1504098396866] ------------------------------ 66ms
2017-08-30T13:06:36.869Z - info: [1504098396868] ------------------------------ 90ms
2017-08-30T13:06:38.865Z - info: 
[1504098398864] ---------------------------- MPD announces system state update
2017-08-30T13:06:38.867Z - info: Mpd Status Update: playlist
2017-08-30T13:06:38.869Z - info: 
[1504098398868] ---------------------------- MPD announces state update
2017-08-30T13:06:38.871Z - info: [1504098398870] parsing response...
2017-08-30T13:06:38.873Z - info: [1504098398872] ControllerMpd::getState
2017-08-30T13:06:38.874Z - info: [1504098398873] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:38.876Z - info: [1504098398875] CoreStateMachine::setConsumeUpdateService mpd
2017-08-30T13:06:38.878Z - info: [1504098398877] ControllerMpd::sendMpdCommand play
2017-08-30T13:06:38.881Z - info: [1504098398880] sending command...
2017-08-30T13:06:38.885Z - info: [1504098398884] sending command...
2017-08-30T13:06:38.890Z - info: [1504098398889] ------------------------------ 25ms
2017-08-30T13:06:38.892Z - info: [1504098398891] parsing response...
2017-08-30T13:06:38.894Z - info: [1504098398893] parsing response...
2017-08-30T13:06:38.895Z - info: [1504098398894] ControllerMpd::parseState
2017-08-30T13:06:38.896Z - info: [1504098398896] ControllerMpd::pushState
2017-08-30T13:06:38.898Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:38.899Z - info: [1504098398898] CoreStateMachine::syncState
2017-08-30T13:06:38.901Z - info: [1504098398900] CorePlayQueue::getTrack 1
2017-08-30T13:06:38.903Z - info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
2017-08-30T13:06:38.905Z - info: CURRENT POSITION 1
2017-08-30T13:06:38.906Z - info: [1504098398905] CoreStateMachine::syncState   stateService stop
2017-08-30T13:06:38.907Z - info: [1504098398906] CoreStateMachine::syncState   currentStatus stop
2017-08-30T13:06:38.909Z - info: [1504098398908] CoreStateMachine::pushState
2017-08-30T13:06:38.910Z - info: [1504098398909] CoreStateMachine::getState
2017-08-30T13:06:38.913Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:38.914Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:38.918Z - info: [1504098398916] interfaceApi::pushState
2017-08-30T13:06:38.919Z - info: [1504098398918] InterfaceWebUI::pushState
2017-08-30T13:06:38.962Z - info: [1504098398961] No code
2017-08-30T13:06:38.964Z - info: [1504098398963] CoreStateMachine::pushState
2017-08-30T13:06:38.965Z - info: [1504098398964] CoreStateMachine::getState
2017-08-30T13:06:38.967Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:38.968Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:38.970Z - info: [1504098398969] interfaceApi::pushState
2017-08-30T13:06:38.972Z - info: [1504098398971] InterfaceWebUI::pushState
2017-08-30T13:06:39.018Z - info: [1504098399017] ------------------------------ 151ms
2017-08-30T13:06:39.028Z - info: Pushing Favourites {"service":"mpd","favourite":false}
2017-08-30T13:06:39.035Z - info: Pushing Favourites {"service":"mpd","favourite":false}
2017-08-30T13:06:42.283Z - info: Mpd Status Update: player
2017-08-30T13:06:42.285Z - info: 
[1504098402283] ---------------------------- MPD announces state update
2017-08-30T13:06:42.287Z - info: [1504098402286] ControllerMpd::getState
2017-08-30T13:06:42.288Z - info: [1504098402287] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:42.290Z - info: [1504098402289] sending command...
2017-08-30T13:06:42.296Z - info: Mpd Status Update: player
2017-08-30T13:06:42.299Z - info: 
[1504098402297] ---------------------------- MPD announces state update
2017-08-30T13:06:42.301Z - info: [1504098402300] parsing response...
2017-08-30T13:06:42.304Z - info: [1504098402303] ControllerMpd::getState
2017-08-30T13:06:42.305Z - info: [1504098402304] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:42.307Z - info: [1504098402306] ControllerMpd::parseState
2017-08-30T13:06:42.309Z - info: [1504098402308] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:42.310Z - info: [1504098402309] sending command...
2017-08-30T13:06:42.313Z - info: [1504098402311] sending command...
2017-08-30T13:06:42.318Z - info: 
[1504098402316] ---------------------------- MPD announces system state update
2017-08-30T13:06:42.326Z - info: Mpd Status Update: playlist
2017-08-30T13:06:42.328Z - info: 
[1504098402327] ---------------------------- MPD announces state update
2017-08-30T13:06:42.330Z - info: 
[1504098402329] ---------------------------- MPD announces system state update
2017-08-30T13:06:42.331Z - info: Mpd Status Update: playlist
2017-08-30T13:06:42.332Z - info: 
[1504098402331] ---------------------------- MPD announces state update
2017-08-30T13:06:42.334Z - info: Mpd Status Update: player
2017-08-30T13:06:42.335Z - info: 
[1504098402334] ---------------------------- MPD announces state update
2017-08-30T13:06:42.337Z - info: [1504098402336] ControllerMpd::getState
2017-08-30T13:06:42.338Z - info: [1504098402337] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:42.339Z - info: [1504098402338] parsing response...
2017-08-30T13:06:42.341Z - info: [1504098402340] ControllerMpd::getState
2017-08-30T13:06:42.342Z - info: [1504098402341] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:42.344Z - info: [1504098402343] ControllerMpd::getState
2017-08-30T13:06:42.345Z - info: [1504098402344] ControllerMpd::sendMpdCommand status
2017-08-30T13:06:42.346Z - info: [1504098402345] parsing response...
2017-08-30T13:06:42.348Z - info: [1504098402348] sending command...
2017-08-30T13:06:42.351Z - info: [1504098402350] ControllerMpd::parseState
2017-08-30T13:06:42.352Z - info: [1504098402351] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:42.354Z - info: [1504098402353] sending command...
2017-08-30T13:06:42.357Z - info: [1504098402356] sending command...
2017-08-30T13:06:42.359Z - info: [1504098402358] ControllerMpd::parseTrackInfo
2017-08-30T13:06:42.362Z - info: [1504098402361] sending command...
2017-08-30T13:06:42.375Z - info: [1504098402374] ControllerMpd::pushState
2017-08-30T13:06:42.376Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:42.378Z - info: [1504098402377] CoreStateMachine::syncState
2017-08-30T13:06:42.379Z - info: [1504098402378] CorePlayQueue::getTrack 1
2017-08-30T13:06:42.381Z - info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"@ Sunrise - Afterlife *","artist":"ABCLounge","album":null,"uri":"http://listen.radionomy.com/ABC-Lounge","trackType":"com/ABC-Lounge"}
2017-08-30T13:06:42.382Z - info: CURRENT POSITION 1
2017-08-30T13:06:42.387Z - info: [1504098402386] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:42.388Z - info: [1504098402387] CoreStateMachine::syncState   currentStatus stop
2017-08-30T13:06:42.391Z - info: [1504098402390] CoreStateMachine::pushState
2017-08-30T13:06:42.392Z - info: [1504098402392] CoreStateMachine::getState
2017-08-30T13:06:42.393Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.395Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.397Z - info: [1504098402396] interfaceApi::pushState
2017-08-30T13:06:42.398Z - info: [1504098402398] InterfaceWebUI::pushState
2017-08-30T13:06:42.454Z - info: [1504098402452] ------------------------------ 171ms
2017-08-30T13:06:42.457Z - info: Pushing Favourites {"service":"mpd","favourite":false}
2017-08-30T13:06:42.467Z - info: [1504098402466] ------------------------------ 150ms
2017-08-30T13:06:42.469Z - info: [1504098402468] parsing response...
2017-08-30T13:06:42.471Z - info: [1504098402470] ------------------------------ 141ms
2017-08-30T13:06:42.472Z - info: [1504098402471] parsing response...
2017-08-30T13:06:42.474Z - info: [1504098402473] parsing response...
2017-08-30T13:06:42.476Z - info: [1504098402475] parsing response...
2017-08-30T13:06:42.477Z - info: [1504098402476] ControllerMpd::parseState
2017-08-30T13:06:42.479Z - info: [1504098402478] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:42.480Z - info: [1504098402479] ControllerMpd::parseState
2017-08-30T13:06:42.482Z - info: [1504098402481] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:42.483Z - info: [1504098402482] ControllerMpd::parseState
2017-08-30T13:06:42.485Z - info: [1504098402484] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:06:42.486Z - info: [1504098402485] ControllerMpd::parseTrackInfo
2017-08-30T13:06:42.488Z - info: [1504098402487] sending command...
2017-08-30T13:06:42.491Z - info: [1504098402490] sending command...
2017-08-30T13:06:42.495Z - info: [1504098402494] sending command...
2017-08-30T13:06:42.498Z - info: [1504098402497] ControllerMpd::pushState
2017-08-30T13:06:42.499Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:42.500Z - info: [1504098402499] CoreStateMachine::syncState
2017-08-30T13:06:42.501Z - info: [1504098402500] CorePlayQueue::getTrack 1
2017-08-30T13:06:42.502Z - info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"@ Sunrise - Afterlife *","artist":"ABCLounge","album":null,"uri":"http://listen.radionomy.com/ABC-Lounge","trackType":"com/ABC-Lounge"}
2017-08-30T13:06:42.503Z - info: CURRENT POSITION 1
2017-08-30T13:06:42.504Z - info: [1504098402504] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:42.505Z - info: [1504098402505] CoreStateMachine::syncState   currentStatus play
2017-08-30T13:06:42.507Z - info: [1504098402506] Received an update from plaugin. extracting info from payload
2017-08-30T13:06:42.508Z - info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
2017-08-30T13:06:42.516Z - info: [1504098402515] CoreStateMachine::pushState
2017-08-30T13:06:42.517Z - info: [1504098402516] CoreStateMachine::getState
2017-08-30T13:06:42.520Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.521Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.525Z - info: [1504098402524] interfaceApi::pushState
2017-08-30T13:06:42.526Z - info: [1504098402525] InterfaceWebUI::pushState
2017-08-30T13:06:42.572Z - info: [1504098402571] CoreStateMachine::pushState
2017-08-30T13:06:42.574Z - info: [1504098402572] CoreStateMachine::getState
2017-08-30T13:06:42.575Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.577Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.580Z - info: [1504098402578] interfaceApi::pushState
2017-08-30T13:06:42.581Z - info: [1504098402580] InterfaceWebUI::pushState
2017-08-30T13:06:42.656Z - info: [1504098402655] ------------------------------ 360ms
2017-08-30T13:06:42.693Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:42.702Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:42.709Z - info: [1504098402708] parsing response...
2017-08-30T13:06:42.710Z - info: [1504098402710] parsing response...
2017-08-30T13:06:42.712Z - info: [1504098402711] parsing response...
2017-08-30T13:06:42.714Z - info: [1504098402713] ControllerMpd::parseTrackInfo
2017-08-30T13:06:42.716Z - info: [1504098402715] ControllerMpd::parseTrackInfo
2017-08-30T13:06:42.717Z - info: [1504098402716] ControllerMpd::parseTrackInfo
2017-08-30T13:06:42.719Z - info: [1504098402718] ControllerMpd::pushState
2017-08-30T13:06:42.720Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:42.721Z - info: [1504098402720] CoreStateMachine::syncState
2017-08-30T13:06:42.721Z - info: [1504098402721] CorePlayQueue::getTrack 1
2017-08-30T13:06:42.723Z - info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"@ Sunrise - Afterlife *","artist":"ABCLounge","album":null,"uri":"http://listen.radionomy.com/ABC-Lounge","trackType":"com/ABC-Lounge"}
2017-08-30T13:06:42.723Z - info: CURRENT POSITION 1
2017-08-30T13:06:42.724Z - info: [1504098402724] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:42.725Z - info: [1504098402724] CoreStateMachine::syncState   currentStatus play
2017-08-30T13:06:42.726Z - info: [1504098402725] Received an update from plaugin. extracting info from payload
2017-08-30T13:06:42.727Z - info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
2017-08-30T13:06:42.729Z - info: [1504098402728] CoreStateMachine::pushState
2017-08-30T13:06:42.730Z - info: [1504098402729] CoreStateMachine::getState
2017-08-30T13:06:42.731Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.732Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.735Z - info: [1504098402735] interfaceApi::pushState
2017-08-30T13:06:42.736Z - info: [1504098402736] InterfaceWebUI::pushState
2017-08-30T13:06:42.769Z - info: [1504098402768] CoreStateMachine::pushState
2017-08-30T13:06:42.771Z - info: [1504098402770] CoreStateMachine::getState
2017-08-30T13:06:42.772Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.773Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.775Z - info: [1504098402774] interfaceApi::pushState
2017-08-30T13:06:42.776Z - info: [1504098402775] InterfaceWebUI::pushState
2017-08-30T13:06:42.808Z - info: [1504098402807] ControllerMpd::pushState
2017-08-30T13:06:42.810Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:42.811Z - info: [1504098402811] CoreStateMachine::syncState
2017-08-30T13:06:42.812Z - info: [1504098402812] CorePlayQueue::getTrack 1
2017-08-30T13:06:42.816Z - info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"@ Sunrise - Afterlife *","artist":"ABCLounge","album":null,"uri":"http://listen.radionomy.com/ABC-Lounge","trackType":"com/ABC-Lounge"}
2017-08-30T13:06:42.817Z - info: CURRENT POSITION 1
2017-08-30T13:06:42.819Z - info: [1504098402818] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:42.820Z - info: [1504098402820] CoreStateMachine::syncState   currentStatus play
2017-08-30T13:06:42.821Z - info: [1504098402821] Received an update from plaugin. extracting info from payload
2017-08-30T13:06:42.822Z - info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
2017-08-30T13:06:42.826Z - info: [1504098402825] CoreStateMachine::pushState
2017-08-30T13:06:42.828Z - info: [1504098402827] CoreStateMachine::getState
2017-08-30T13:06:42.829Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.830Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.832Z - info: [1504098402831] interfaceApi::pushState
2017-08-30T13:06:42.833Z - info: [1504098402833] InterfaceWebUI::pushState
2017-08-30T13:06:42.868Z - info: [1504098402867] CoreStateMachine::pushState
2017-08-30T13:06:42.871Z - info: [1504098402868] CoreStateMachine::getState
2017-08-30T13:06:42.875Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.876Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.887Z - info: [1504098402886] interfaceApi::pushState
2017-08-30T13:06:42.889Z - info: [1504098402888] InterfaceWebUI::pushState
2017-08-30T13:06:42.922Z - info: [1504098402921] ControllerMpd::pushState
2017-08-30T13:06:42.923Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:06:42.924Z - info: [1504098402923] CoreStateMachine::syncState
2017-08-30T13:06:42.925Z - info: [1504098402924] CorePlayQueue::getTrack 1
2017-08-30T13:06:42.927Z - info: STATE SERVICE {"status":"play","position":0,"seek":487,"duration":0,"samplerate":"44.1 KHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"@ Sunrise - Afterlife *","artist":"ABCLounge","album":null,"uri":"http://listen.radionomy.com/ABC-Lounge","trackType":"com/ABC-Lounge"}
2017-08-30T13:06:42.937Z - info: CURRENT POSITION 1
2017-08-30T13:06:42.939Z - info: [1504098402938] CoreStateMachine::syncState   stateService play
2017-08-30T13:06:42.941Z - info: [1504098402940] CoreStateMachine::syncState   currentStatus play
2017-08-30T13:06:42.942Z - info: [1504098402941] Received an update from plaugin. extracting info from payload
2017-08-30T13:06:42.944Z - info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt
2017-08-30T13:06:42.945Z - info: [1504098402944] CoreStateMachine::pushState
2017-08-30T13:06:42.947Z - info: [1504098402946] CoreStateMachine::getState
2017-08-30T13:06:42.956Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.957Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:42.959Z - info: [1504098402958] interfaceApi::pushState
2017-08-30T13:06:42.961Z - info: [1504098402960] InterfaceWebUI::pushState
2017-08-30T13:06:42.995Z - info: [1504098402994] CoreStateMachine::pushState
2017-08-30T13:06:42.997Z - info: [1504098402996] CoreStateMachine::getState
2017-08-30T13:06:42.998Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:06:42.999Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:06:43.001Z - info: [1504098403000] interfaceApi::pushState
2017-08-30T13:06:43.003Z - info: [1504098403002] InterfaceWebUI::pushState
2017-08-30T13:06:43.049Z - info: [1504098403048] ------------------------------ 730ms
2017-08-30T13:06:43.052Z - info: [1504098403051] ------------------------------ 721ms
2017-08-30T13:06:43.055Z - info: [1504098403054] ------------------------------ 721ms
2017-08-30T13:06:43.080Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:43.085Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:43.091Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:43.096Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:43.101Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}
2017-08-30T13:06:43.107Z - info: Pushing Favourites {"service":"webradio","uri":"http://listen.radionomy.com/ABC-Lounge","favourite":false}

<press stop>
2017-08-30T13:08:40.382Z - info: 
[1504098520381] ---------------------------- Client requests Volumio stop
2017-08-30T13:08:40.385Z - info: CoreCommandRouter::volumioStop
2017-08-30T13:08:40.387Z - info: [1504098520386] CoreStateMachine::stop
2017-08-30T13:08:40.388Z - info: [1504098520387] CoreStateMachine::setConsumeUpdateService undefined
2017-08-30T13:08:40.389Z - info: [1504098520388] CoreStateMachine::stPlaybackTimer
2017-08-30T13:08:40.390Z - info: [1504098520390] CoreStateMachine::updateTrackBlock
2017-08-30T13:08:40.391Z - info: [1504098520391] CorePlayQueue::getTrackBlock
2017-08-30T13:08:40.392Z - info: [1504098520392] CoreStateMachine::pushState
2017-08-30T13:08:40.394Z - info: [1504098520393] CoreStateMachine::getState
2017-08-30T13:08:40.395Z - info: [1504098520394] CorePlayQueue::getTrack 1
2017-08-30T13:08:40.397Z - info: CoreCommandRouter::volumioPushState
2017-08-30T13:08:40.398Z - info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
2017-08-30T13:08:40.401Z - info: [1504098520400] interfaceApi::pushState
2017-08-30T13:08:40.403Z - info: [1504098520402] InterfaceWebUI::pushState
2017-08-30T13:08:40.452Z - info: [1504098520450] CoreStateMachine::serviceStop
2017-08-30T13:08:40.453Z - info: [1504098520452] CorePlayQueue::getTrack 1
2017-08-30T13:08:40.455Z - info: CoreCommandRouter::serviceStop
2017-08-30T13:08:40.460Z - info: [1504098520458] ControllerWebradio::stop
2017-08-30T13:08:40.461Z - info: [1504098520460] ControllerMpd::sendMpdCommand stop
2017-08-30T13:08:40.469Z - info: [1504098520468] sending command...
2017-08-30T13:08:40.474Z - info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-station.m3u?id=709809","favourite":false}
2017-08-30T13:08:40.570Z - info: [1504098520569] parsing response...
2017-08-30T13:08:40.573Z - info: [1504098520572] ------------------------------ 191ms
2017-08-30T13:08:40.575Z - info: Mpd Status Update: player
2017-08-30T13:08:40.576Z - info: 
[1504098520575] ---------------------------- MPD announces state update
2017-08-30T13:08:40.578Z - info: [1504098520577] ControllerMpd::getState
2017-08-30T13:08:40.579Z - info: [1504098520578] ControllerMpd::sendMpdCommand status
2017-08-30T13:08:40.580Z - info: [1504098520579] sending command...
2017-08-30T13:08:40.584Z - info: [1504098520583] parsing response...
2017-08-30T13:08:40.586Z - info: [1504098520585] ControllerMpd::parseState
2017-08-30T13:08:40.587Z - info: [1504098520586] ControllerMpd::sendMpdCommand playlistinfo
2017-08-30T13:08:40.588Z - info: [1504098520588] sending command...
2017-08-30T13:08:40.592Z - info: [1504098520591] parsing response...
2017-08-30T13:08:40.594Z - info: [1504098520593] ControllerMpd::parseTrackInfo
2017-08-30T13:08:40.595Z - info: [1504098520594] ControllerMpd::pushState
2017-08-30T13:08:40.596Z - info: CoreCommandRouter::servicePushState
2017-08-30T13:08:40.597Z - info: [1504098520596] CoreStateMachine::syncState
2017-08-30T13:08:40.598Z - info: [1504098520597] CorePlayQueue::getTrack 1
2017-08-30T13:08:40.599Z - info: [1504098520598] Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd
2017-08-30T13:08:40.600Z - info: [1504098520599] ------------------------------ 25ms
xipmix commented 7 years ago

When I remove the webradio item from the queue (playback still stopped), the playback page moves on to the next album track. I can then press play and move back and forward between the album tracks with no issue.

xipmix commented 7 years ago

After a bit of poking and prying I found some problems with webradio handling, but not the cause of this issue. Then I turned to the UI code

So this appears to be deliberate ... but evidently not a behaviour users are expecting.

I'm also unsure how trackType ever gets to be webradio. In the log excerpt above trackType is actually com/ABC-Lounge. The original url of the webradio is
http://yp.shoutcast.com/sbin/tunein-station.m3u?id=709809
The URL that comes back from the service (in a .m3u file) is
http://listen.radionomy.com/ABC-Lounge.com/ABC-Lounge
which is then mangled into these fields: uri: "http://listen.radionomy.com/ABC-Lounge" and trackType: "com/ABC-Lounge" by https://github.com/volumio/Volumio2/blob/bbe4e278112a0ad5d266695f3c6741b016592d16/app/plugins/music_service/mpd/index.js#L378

I can see where the state machine tries to set it to webradio: https://github.com/volumio/Volumio2/blob/bbe4e278112a0ad5d266695f3c6741b016592d16/app/statemachine.js#L104 but it's unclear whether that works in this case or is even relevant.

xipmix commented 7 years ago

I was unable to get the UI code to log the track type

next() {
+  this.$log.debug('next called with trackType', this.state.trackType);

If I disable the restriction about webradio in the UI next function and then press next while playing a webradio, I get:

Sep 03 01:19:21 volumio volumio[3840]: info:                                    
Sep 03 01:19:21 volumio volumio[3840]: [1504401561624] ---------------------------- Client requests Volumio next
Sep 03 01:19:21 volumio volumio[3840]: info: CoreCommandRouter::volumioNext     
Sep 03 01:19:21 volumio volumio[3840]: info: [1504401561634] CoreStateMachine::next
Sep 03 01:19:21 volumio volumio[3840]: info: Error:                             
Sep 03 01:19:21 volumio volumio[3840]: TypeError: thisPlugin.next is not a function
Sep 03 01:19:21 volumio volumio[3840]: at CoreStateMachine.next (/volumio/app/statemachine.js:1162:15)
Sep 03 01:19:21 volumio volumio[3840]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:108:27)
Sep 03 01:19:21 volumio volumio[3840]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Sep 03 01:19:21 volumio volumio[3840]: at _combinedTickCallback (internal/process/next_tick.js:73:7)
Sep 03 01:19:21 volumio volumio[3840]: at process._tickCallback (internal/process/next_tick.js:104:9)
Sep 03 01:19:21 volumio volumio[3840]: info: [1504401561649] ------------------------------ 25ms
Sep 03 01:19:40 volumio volumio[3840]: info:

With the restriction still disabled, and the webradio track selected but stopped, pressing 'next' does the right thing.

<pressed stop>                                                                 
Sep 03 01:19:40 volumio volumio[3840]: info:                                    
Sep 03 01:19:40 volumio volumio[3840]: [1504401580347] ---------------------------- Client requests Volumio stop
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::volumioStop     
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580356] CoreStateMachine::stop
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580358] CoreStateMachine::setConsumeUpdateService undefined
Sep 03 01:19:40 volumio volumio[3840]: UNSET VOLATILE                           
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580361] CoreStateMachine::stPlaybackTimer
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580363] CoreStateMachine::updateTrackBlock
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580367] CorePlayQueue::getTrackBlock
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580370] CoreStateMachine::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580371] CoreStateMachine::getState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580373] CorePlayQueue::getTrack 1
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580381] interfaceApi::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580382] InterfaceWebUI::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580432] CoreStateMachine::serviceStop
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580434] CorePlayQueue::getTrack 1
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580381] interfaceApi::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580382] InterfaceWebUI::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580432] CoreStateMachine::serviceStop
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580434] CorePlayQueue::getTrack 1
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::serviceStop     
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580439] ControllerWebradio::stop
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580441] ControllerMpd::sendMpdCommand stop
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580451] sending command... 
Sep 03 01:19:40 volumio volumio[3840]: info: Pushing Favourites {"service":"webradio","uri":"http://yp.shoutcast.com/sbin/tunein-  station.m3u?id=709809","favourite":false}
Sep 03 01:19:40 volumio volumio[3840]: info: No input data                      
Sep 03 01:19:40 volumio volumio[3840]: info: Mpd Status Update: player          
Sep 03 01:19:40 volumio volumio[3840]: info:                                    
Sep 03 01:19:40 volumio volumio[3840]: [1504401580571] ---------------------------- MPD announces state update
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580574] parsing response...
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580576] ControllerMpd::getState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580578] ControllerMpd::sendMpdCommand status
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580580] ------------------------------ 233ms
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580582] sending command... 
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580587] parsing response...
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580590] ControllerMpd::parseState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580592] ControllerMpd::sendMpdCommand playlistinfo
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580594] sending command... 
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580599] parsing response...
Sep 03 01:19:40 volumio volumio[3840]: DEBUG collectedState  { status: 'stop',  
Sep 03 01:19:40 volumio volumio[3840]: position: 0,                             
Sep 03 01:19:40 volumio volumio[3840]: seek: null,                              
Sep 03 01:19:40 volumio volumio[3840]: duration: null,                          
Sep 03 01:19:40 volumio volumio[3840]: samplerate: null,                        
Sep 03 01:19:40 volumio volumio[3840]: bitdepth: null,                          
Sep 03 01:19:40 volumio volumio[3840]: channels: null,                          
Sep 03 01:19:40 volumio volumio[3840]: random: false,                           
Sep 03 01:19:40 volumio volumio[3840]: updatedb: false,                         
Sep 03 01:19:40 volumio volumio[3840]: repeat: false }                          
Sep 03 01:19:40 volumio volumio[3840]: DEBUG objTrackInfo  { file: 'http://listen.radionomy.com/ABC-Lounge',
Sep 03 01:19:40 volumio volumio[3840]: Title: '* Ben Harper - Waiting On An Angel @',
Sep 03 01:19:40 volumio volumio[3840]: Name: 'ABCLounge',                       
Sep 03 01:19:40 volumio volumio[3840]: Time: '0',                               
Sep 03 01:19:40 volumio volumio[3840]: duration: '0.000',                       
Sep 03 01:19:40 volumio volumio[3840]: Pos: '0',                                
Sep 03 01:19:40 volumio volumio[3840]: Id: '3' }                                
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580612] ControllerMpd::parseTrackInfo 
Sep 03 01:19:40 volumio volumio[3840]: info: "OBJTRACKINFO {\"file\":\"http://listen.radionomy.com/ABC-Lounge\",\"Title\":\"* Ben  Harper - Waiting On An Angel @\",\"Name\":\"ABCLounge\",\"Time\":\"0\",\"duration\":\"0.000\",\"Pos\":\"0\",\"Id\":\"3\"}"
Sep 03 01:19:40 volumio volumio[3840]: info: OBJTRACKINFO.TIME is _0_           
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580619] ControllerMpd::pushState
Sep 03 01:19:40 volumio volumio[3840]: info: CoreCommandRouter::servicePushState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580622] CoreStateMachine::syncState
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580624] CorePlayQueue::getTrack 1
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580625] Received update from a service different from the one supposed to be  playing music. Skipping notification.Current webradio Received mpd
Sep 03 01:19:40 volumio volumio[3840]: info: [1504401580627] ------------------------------ 58ms

<playback stopped, press next>
Sep 03 01:21:44 volumio volumio[3840]: info:                                    
Sep 03 01:21:44 volumio volumio[3840]: [1504401704869] ---------------------------- Client requests Volumio next
Sep 03 01:21:44 volumio volumio[3840]: info: CoreCommandRouter::volumioNext     
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704873] CoreStateMachine::next
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704874] CoreStateMachine::stop
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704876] CoreStateMachine::setConsumeUpdateService undefined
Sep 03 01:21:44 volumio volumio[3840]: UNSET VOLATILE                           
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704879] ------------------------------ 10ms
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704881] CoreStateMachine::play index undefined
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704882] CoreStateMachine::setConsumeUpdateService undefined
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704884] CorePlayQueue::getTrack 2
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704886] CoreStateMachine::startPlaybackTimer
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704888] CorePlayQueue::getTrack 2
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704895] ControllerMpd::clearAddPlayTracks NAS/testu2/Rattle_and_Hum/04-       Hawkmoon_269.flac
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704897] ControllerMpd::sendMpdCommand stop
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704913] sending command... 
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704947] CoreStateMachine::updateTrackBlock
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704949] CorePlayQueue::getTrackBlock
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704958] parsing response...
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704961] ControllerMpd::sendMpdCommand clear
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704963] sending command... 
Sep 03 01:21:44 volumio volumio[3840]: info:                                    
Sep 03 01:21:44 volumio volumio[3840]: [1504401704967] ---------------------------- MPD announces system state update
Sep 03 01:21:44 volumio volumio[3840]: info: Mpd Status Update: playlist        
Sep 03 01:21:44 volumio volumio[3840]: info:                                    
Sep 03 01:21:44 volumio volumio[3840]: [1504401704970] ---------------------------- MPD announces state update
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704972] parsing response...
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704974] ControllerMpd::getState
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704976] ControllerMpd::sendMpdCommand status
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704979] ControllerMpd::sendMpdCommand add "NAS/testu2/Rattle_and_Hum/04-      Hawkmoon_269.flac"
Sep 03 01:21:44 volumio volumio[3840]: info: [1504401704981] sending command... 
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401704984] sending command... 
Sep 03 01:21:45 volumio volumio[3840]: info:                                    
Sep 03 01:21:45 volumio volumio[3840]: info:                                    
Sep 03 01:21:45 volumio volumio[3840]: [1504401704991] ---------------------------- MPD announces system state update
Sep 03 01:21:45 volumio volumio[3840]: info: Mpd Status Update: playlist        
Sep 03 01:21:45 volumio volumio[3840]: info:                                    
Sep 03 01:21:45 volumio volumio[3840]: [1504401704994] ---------------------------- MPD announces state update
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401704996] ------------------------------ 29ms
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401704997] parsing response...
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401704999] parsing response...
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705001] ControllerMpd::getState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705003] ControllerMpd::sendMpdCommand status
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705004] ControllerMpd::parseState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705008] ControllerMpd::sendMpdCommand play
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705010] sending command... 
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705013] ControllerMpd::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::servicePushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705015] CoreStateMachine::syncState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705016] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,           "samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null, "artist":null,"album":null,"uri":null}
Sep 03 01:21:45 volumio volumio[3840]: info: CURRENT POSITION 2                 
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705021] CoreStateMachine::syncState   stateService stop
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705022] CoreStateMachine::syncState   currentStatus stop
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705023] CoreStateMachine::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705025] CoreStateMachine::getState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705026] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705033] interfaceApi::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705035] InterfaceWebUI::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705079] No code            
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705081] CoreStateMachine::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705082] CoreStateMachine::getState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705083] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705089] interfaceApi::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705091] InterfaceWebUI::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705127] sending command... 
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705141] ------------------------------ 172ms
Sep 03 01:21:45 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:45 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705171] ------------------------------ 180ms
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705172] parsing response...
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705174] parsing response...
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705175] ControllerMpd::parseState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705177] ControllerMpd::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::servicePushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705179] CoreStateMachine::syncState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705180] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,           "samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":null, "artist":null,"album":null,"uri":null}
Sep 03 01:21:45 volumio volumio[3840]: info: CURRENT POSITION 2                 
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705184] CoreStateMachine::syncState   stateService stop
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705185] CoreStateMachine::syncState   currentStatus stop
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705187] CoreStateMachine::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705188] CoreStateMachine::getState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705189] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705194] interfaceApi::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705195] InterfaceWebUI::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705251] No code            
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705253] CoreStateMachine::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705254] CoreStateMachine::getState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705256] CorePlayQueue::getTrack 2
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:45 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705262] interfaceApi::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705264] InterfaceWebUI::pushState
Sep 03 01:21:45 volumio volumio[3840]: info: [1504401705296] ------------------------------ 305ms
Sep 03 01:21:45 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:45 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:47 volumio volumio[3840]: info: Mpd Status Update: player          
Sep 03 01:21:47 volumio volumio[3840]: info:                                    
Sep 03 01:21:47 volumio volumio[3840]: [1504401707404] ---------------------------- MPD announces state update
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707409] ControllerMpd::getState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707418] ControllerMpd::sendMpdCommand status
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707420] sending command... 
Sep 03 01:21:47 volumio volumio[3840]: info: Mpd Status Update: player          
Sep 03 01:21:47 volumio volumio[3840]: info:                                    
Sep 03 01:21:47 volumio volumio[3840]: [1504401707441] ---------------------------- MPD announces state update
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707443] ControllerMpd::getState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707444] ControllerMpd::sendMpdCommand status
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707446] parsing response...
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707449] sending command... 
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707451] ControllerMpd::parseState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707453] ControllerMpd::sendMpdCommand playlistinfo
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707455] sending command... 
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707468] parsing response...
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707470] parsing response...
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707473] ControllerMpd::parseState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707474] ControllerMpd::sendMpdCommand playlistinfo
Sep 03 01:21:47 volumio volumio[3840]: DEBUG collectedState  { status: 'play',  
Sep 03 01:21:47 volumio volumio[3840]: position: 0,                             
Sep 03 01:21:47 volumio volumio[3840]: seek: 2345,                              
Sep 03 01:21:47 volumio volumio[3840]: duration: 382,                           
Sep 03 01:21:47 volumio volumio[3840]: samplerate: '44.1 KHz',                  
Sep 03 01:21:47 volumio volumio[3840]: bitdepth: '16 bit',                      
Sep 03 01:21:47 volumio volumio[3840]: channels: 2,                             
Sep 03 01:21:47 volumio volumio[3840]: random: false,                           
Sep 03 01:21:47 volumio volumio[3840]: updatedb: false,                         
Sep 03 01:21:47 volumio volumio[3840]: repeat: false }                          
Sep 03 01:21:47 volumio volumio[3840]: DEBUG objTrackInfo  { file: 'NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac',
Sep 03 01:21:47 volumio volumio[3840]: 'Last-Modified': '2008-07-19T08:37:21Z', 
Sep 03 01:21:47 volumio volumio[3840]: Artist: 'U2',                            
Sep 03 01:21:47 volumio volumio[3840]: Album: 'Rattle and Hum',                 
Sep 03 01:21:47 volumio volumio[3840]: Title: 'Hawkmoon 269',                   
Sep 03 01:21:47 volumio volumio[3840]: Genre: 'Misc',                           
Sep 03 01:21:47 volumio volumio[3840]: Track: '4',                              
Sep 03 01:21:47 volumio volumio[3840]: Time: '382',                             
Sep 03 01:21:47 volumio volumio[3840]: duration: '382.413',                     
Sep 03 01:21:47 volumio volumio[3840]: Pos: '0',                                
Sep 03 01:21:47 volumio volumio[3840]: Id: '4' }                                
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707485] ControllerMpd::parseTrackInfo
Sep 03 01:21:47 volumio volumio[3840]: info: "OBJTRACKINFO {\"file\":\"NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac\",\"Last-    Modified\":\"2008-07-19T08:37:21Z\",\"Artist\":\"U2\",\"Album\":\"Rattle and Hum\",\"Title\":\"Hawkmoon 269\",\"Genre\":\"Misc\",  \"Track\":\"4\",\"Time\":\"382\",\"duration\":\"382.413\",\"Pos\":\"0\",\"Id\":\"4\"}"
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707491] sending command... 
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707494] ControllerMpd::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::servicePushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707497] CoreStateMachine::syncState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707499] CorePlayQueue::getTrack 2
Sep 03 01:21:47 volumio volumio[3840]: info: STATE SERVICE {"status":"play","position":0,"seek":2345,"duration":382,"samplerate":  "44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hawkmoon   269","artist":"U2","album":"Rattle and Hum","uri":"NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac","trackType":"flac"}
Sep 03 01:21:47 volumio volumio[3840]: info: CURRENT POSITION 2                 
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707505] CoreStateMachine::syncState   stateService play
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707507] CoreStateMachine::syncState   currentStatus stop
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707511] CoreStateMachine::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707514] CoreStateMachine::getState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707515] CorePlayQueue::getTrack 2
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707523] interfaceApi::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707526] InterfaceWebUI::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707589] ------------------------------ 189ms
Sep 03 01:21:47 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707613] parsing response...
Sep 03 01:21:47 volumio volumio[3840]: DEBUG collectedState  { status: 'play',  
Sep 03 01:21:47 volumio volumio[3840]: position: 0,                             
Sep 03 01:21:47 volumio volumio[3840]: seek: 2345,                              
Sep 03 01:21:47 volumio volumio[3840]: duration: 382,                           
Sep 03 01:21:47 volumio volumio[3840]: samplerate: '44.1 KHz',                  
Sep 03 01:21:47 volumio volumio[3840]: bitdepth: '16 bit',                      
Sep 03 01:21:47 volumio volumio[3840]: channels: 2,                             
Sep 03 01:21:47 volumio volumio[3840]: random: false,                           
Sep 03 01:21:47 volumio volumio[3840]: updatedb: false,                         
Sep 03 01:21:47 volumio volumio[3840]: repeat: false }                          
Sep 03 01:21:47 volumio volumio[3840]: DEBUG objTrackInfo  { file: 'NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac',
Sep 03 01:21:47 volumio volumio[3840]: 'Last-Modified': '2008-07-19T08:37:21Z', 
Sep 03 01:21:47 volumio volumio[3840]: Artist: 'U2',                            
Sep 03 01:21:47 volumio volumio[3840]: Album: 'Rattle and Hum',                 
Sep 03 01:21:47 volumio volumio[3840]: Title: 'Hawkmoon 269',                   
Sep 03 01:21:47 volumio volumio[3840]: Genre: 'Misc',                           
Sep 03 01:21:47 volumio volumio[3840]: Track: '4',                              
Sep 03 01:21:47 volumio volumio[3840]: Time: '382',                             
Sep 03 01:21:47 volumio volumio[3840]: duration: '382.413',                     
Sep 03 01:21:47 volumio volumio[3840]: Pos: '0',                                
Sep 03 01:21:47 volumio volumio[3840]: Id: '4' }                                
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707623] ControllerMpd::parseTrackInfo
Sep 03 01:21:47 volumio volumio[3840]: info: "OBJTRACKINFO {\"file\":\"NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac\",\"Last-    Modified\":\"2008-07-19T08:37:21Z\",\"Artist\":\"U2\",\"Album\":\"Rattle and Hum\",\"Title\":\"Hawkmoon 269\",\"Genre\":\"Misc\",  \"Track\":\"4\",\"Time\":\"382\",\"duration\":\"382.413\",\"Pos\":\"0\",\"Id\":\"4\"}"
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707629] ControllerMpd::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::servicePushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707633] CoreStateMachine::syncState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707635] CorePlayQueue::getTrack 2
Sep 03 01:21:47 volumio volumio[3840]: info: STATE SERVICE {"status":"play","position":0,"seek":2345,"duration":382,"samplerate":  "44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Hawkmoon   269","artist":"U2","album":"Rattle and Hum","uri":"NAS/testu2/Rattle_and_Hum/04-Hawkmoon_269.flac","trackType":"flac"}
Sep 03 01:21:47 volumio volumio[3840]: info: CURRENT POSITION 2                 
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707640] CoreStateMachine::syncState   stateService play
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707642] CoreStateMachine::syncState   currentStatus play
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707644] Received an update from plaugin. extracting info from payload
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707646] CoreStateMachine::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707648] CoreStateMachine::getState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707650] CorePlayQueue::getTrack 2
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707656] interfaceApi::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707658] InterfaceWebUI::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707722] CoreStateMachine::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707729] CoreStateMachine::getState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707731] CorePlayQueue::getTrack 2
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::volumioPushState
Sep 03 01:21:47 volumio volumio[3840]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707738] interfaceApi::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707740] InterfaceWebUI::pushState
Sep 03 01:21:47 volumio volumio[3840]: info: [1504401707784] ------------------------------ 345ms
Sep 03 01:21:47 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:21:47 volumio volumio[3840]: info: Pushing Favourites {"service":"mpd","uri":"mnt/NAS/testu2/Rattle_and_Hum/04-          Hawkmoon_269.flac","favourite":false}
Sep 03 01:22:05 volumio volumio[3840]: info:                                    
Sep 03 01:22:05 volumio volumio[3840]: [1504401725310] ---------------------------- Client requests Volumio pause
Sep 03 01:22:05 volumio volumio[3840]: info: CoreCommandRouter::volumioPause
p1234321 commented 6 years ago

hello, it would really be aweseome to be able to navigate through webradios and make a playlist of webradios!!!

R13e commented 6 years ago

hey, any progress? the bug still exists.

TaliesinDS commented 6 years ago

I would be really nice if this was fixed. I based a project around this working properly :(

diehardsk commented 6 years ago

Hi everyone, I'm in the same opinion. To build an internet radio player for everyday use hardware buttons or rotary encoder control is necessary. I followed xipmix's analyse and found we can quite easily overcome problem happening in next/prev function. Modified line 1166 and 1306 in statemachine.js from if (this.isConsume && this.consumeState.service != undefined) { to if (this.isConsume && this.consumeState.service != undefined && this.consumeState.service !== 'webradio') {

And it works just fine. Anyway, I'm new to Volumio and js, so you'd better consider it as a dirty hotfix. Also to be able to use next/prev/repeat in web ui you need to remove webradio restriction as described above.

serperus commented 6 years ago

Hi,

I'm very sorry, but how to

" you need to remove webradio restriction as described above"

Please point me to the right code for that.

Thank you!

diehardsk commented 6 years ago

@serperus if you look at volumio/http/www/scripts/app-697d229700.js you can find the restriction "webradio"!==this.state.trackType&& in some button's function. Save original file and than you can experiment by removing this code.

serperus commented 6 years ago

@diehardsk thank you! I have found this file with grep "webradio" but it seems to be hard to edit and it not so humanreadable :)

but I'll try.

thanks again

serperus commented 6 years ago

Hi,

In order to do next&prev buttons working with webradio you should do the following:

1) modify statemachine.js as described below by @diehardsk in both functuions (thisPlugin.next and thisPlugin.previous) 2) modify file /volumio/http/www/scripts/app-your-version.js and remove code

"webradio"!==this.state.trackType&& from this file (for next and for prev)

Please do backups before performing any modification!

Thank you @diehardsk for your help.

Works like a charm :)

pagaille commented 6 years ago

Working too. I used http://jsbeautifier.org to understand what I was doing on the "obfuscated" js file.

I'd like to submit a PR, but does it make sense to submit it for the js file ?

xipmix commented 6 years ago

In the Volumio2-UI project I think this code https://github.com/volumio/Volumio2-UI/blob/88a6a0c0487ebf5e70da7360ed77673117e6946b/src/app/services/player.service.js#L64-L74 that is what ends up in http/www/scripts/app-your-version.js. I am not sure what transformation is applied to get it there, but I think the change needed is:

--- a/src/app/services/player.service.js
+++ b/src/app/services/player.service.js
@@ -62,15 +62,11 @@ class PlayerService {
   }

   prev() {
-    if (this.state.trackType !== 'webradio') {
       this.socketService.emit('prev');
-    }
   }

   next() {
-    if (this.state.trackType !== 'webradio') {
       this.socketService.emit('next');
-    }
   }

   shuffle() {

So two PRs will be needed to get this in, one for statemachine.js in volumio/Volumio2 and one for player.service.js in volumio/Volumio2-UI.

The combination of both will need to be tested. To test the staemachine.js change, just upload the modified file to your volumio and reboot. The UI project is a little more tricky, see https://github.com/volumio/docs/blob/master/docs/01_Development_How_To/04_Set_up_development_environment_for_UI.md . Basically you modify the player.service.js file in your git clone, build the project and it produces new stuff in the dist/ subdirectory. Copy the contents of dist/* dir to volumio@volumio:/volumio/http/www/ and reboot volumio again. Then you are ready to test that you can move between web radios and also test moving between other content types in the play list still works. @balbuze you made some updates to that section of the docs recently, can we use rsync to copy things over, ie rsync -uv dist/ volumio@volumio:/volumio/http/www/ ?

xlisec commented 6 years ago

What do you think about shuffle() and repeatAlbum() functions in player.service.js? My opinion is remove webradio condition too. Webradio in Volumio 2 is only like infinite song. The movement should be the same as normal songs (prev, next, random, repeat).

ZigUSSS commented 5 years ago

does anyone do anything about the commands in webradio next prev

tiroy1 commented 5 years ago

How the heck such a simple fix is not already in released code?

TonyVpck commented 4 years ago

Hello everyone, An update have been made since the "next/previous webradio" issue was identify but, i've just installed volumio, and the problem remain the same. Can I modify JS files with my navigator (I use Volumio on Raspberry Pi zero) ? Do you think this issue will be solved for a next update ? Have a good day ! Tony

TonyVpck commented 4 years ago

Hello ! I forked the Volumio folders and modified the JS files to be able to test, but I can't find the original JS codes in the tree of my raspberry pi. Did anyone test functional changes that could be added to a futur pull request? Have a good day !

xipmix commented 4 years ago

Changing the UI code is a bit awkward because it gets minified. This page explains: https://volumio.github.io/docs/Development_How_To/Set_up_development_environment_for_UI.html The trick is the last step - manually copying the dist directory to /volumio/http/www. What I've done in the past is

$ rsync -rvt dists/ volumio@volumio:/volumio/http/www/

Can you give that a try and let us know how you get on?

Not sure what you mean by "functional changes" - do you mean changes to the backend code?

TonyVpck commented 4 years ago

Hello,

I just made the manual modifications of this files from the console :

/volumio/app/statemachine.js /volumio/http/www/scripts/app-_yourversionnumber__.js

The changes seem to make the next and prev buttons work !

Do you think it is necessary to make a pull request to bring a solution to the next versions of Volumio ? Do we have to do other tests to make sure the buttons solution is okay ? (I'm new to github, I'm not sure how we can suggest this to developers and who can suggest it).

Have a good day!

xipmix commented 4 years ago

Definitely. You'll need to do two PRs, one for Volumio2 (statemachine.js) and one for Volumio2-UI. Possibly it will help if you could post your changes here inside a code block (three ` marks before and after, on a separate line). Then we can figure out how to prepare the PRs.

As I said the app-yourversionnumber_.js file is a minified version compiled from lots of little bits of code in the Volumio2-UI project. We'll have to find the original files and change those for the pull request to be accepted. Should not be that difficult.

TonyVpck commented 4 years ago

Thank you for help !

To sum up, the modification should be :

@@ -1267,7 +1267,7 @@ CoreStateMachine.prototype.next = function (promisedResponse) {
        }
    } else {
        //self.setConsumeUpdateService(undefined);
    -   if (this.isConsume && this.consumeState.service != undefined) {
    +   if (this.isConsume && this.consumeState.service != undefined && this.consumeState.service !== 'webradio') { 
            var thisPlugin = this.commandRouter.pluginManager.getPlugin('music_service', this.consumeState.service);
            if (typeof thisPlugin.next === "function") {
                thisPlugin.next();
@@ -1427,7 +1427,7 @@ CoreStateMachine.prototype.previous = function (promisedResponse) {
            }

        } else if (this.currentStatus === 'play') {
        -   if (this.isConsume && this.consumeState.service != undefined) {
        +   if (this.isConsume && this.consumeState.service != undefined && this.consumeState.service !== 'webradio') { 
                var thisPlugin = this.commandRouter.getMusicPlugin(this.consumeState.service);
                if (!this.previousTrackonPrev && typeof thisPlugin.seek === "function") {
                    thisPlugin.seek(0);

and as you were able to find it previously, in player.service.js :

@@ -69,15 +69,11 @@ class PlayerService {
  }

  prev() {
-    if (this.state.trackType !== 'webradio') {
      this.socketService.emit('prev');
-    }
  }

  next() {
-   if (this.state.trackType !== 'webradio') {
      this.socketService.emit('next');
-    }
  }

  skipBackwards() {

Have a nice evening !

xipmix commented 4 years ago

Thanks for this. I've been holding off on submitting this for lack of time to figure out how to test the modified UI code, I was stumbling over the minification step. But you've worked that out (by modifying the minifed code - did you beautify it first?), so let's get this done.

First are there changes of behaviour for other sources, like tracks from the music library? And a mix of sources? You want to make sure your change doesn't introduce regressions.

Here's the step by step

This will trigger a change in the github web UI suggesting you submit a pull request (actually two). When you do that, you should mention in the comments what platform you tested on and what tests you did. You should mention this issue too.

TonyVpck commented 4 years ago

Hello !

I just tried to play webradio mixed with flac and mp3 files, everything seems to work fine.

The only thing I noticed that overlaps with a previously written post: It might be a good idea not to make the change just for the next and prev buttons, but extend this to the shuffle and repeat buttons.

The reason is that it is possible to activate shuffle and repeat only on audio files (and not on webradio) while it works for the entire queue !

I plan to add these new changes to my fork before submitting the PR? What do you think ?

In this option PlayerService.js could be >

@@ -69,15 +69,11 @@ class PlayerService {
  }

  prev() {
   - if (this.state.trackType !== 'webradio') {
      this.socketService.emit('prev');
   - }
  }

  next() {
   - if (this.state.trackType !== 'webradio') {
      this.socketService.emit('next');
   - }
  }

@@ -89,16 +85,12 @@ class PlayerService {
  }

  shuffle() {
   - if (this.state.trackType !== 'webradio') {
      this.$log.debug(!this.state.random);
      this.socketService.emit('setRandom', {value: !this.state.random});
   - }
  }

  repeatAlbum(repeat,repeatSingle) {
   - if (this.state.trackType !== 'webradio') {
      this.socketService.emit('setRepeat', {value: repeat, repeatSingle: repeatSingle});
   - }
  }

Good evening ! Tony

xipmix commented 4 years ago

go for it, I reckon it will work

TonyVpck commented 4 years ago

The PR is okay, the modification have been merged !

Thank you for help ! See you next time !

xipmix commented 4 years ago

Can someone close this please, it's done.

DaniilMir commented 2 years ago

For Volumio 3 even with this fix it was not switching to previous stream. Next stream was working properly. I found a way to make it work : File : /volumio/app/statemachine.js

Replace this.previousTrackonPrev = false; with this.previousTrackonPrev = true;

Not sure if this parameter "previousTrackonPrev" was designed to be configurable from UI. But it worked.