I am using Volumio version 2.175 on Raspberry PI3. PI3 connect to external DAC through i2S. I have chosen Generic i2S DAC and play song through upnp. The song stopped randomly. I can resume by press play button again. Following is the volumio log file. Seems device disappear from network. Everyone can help?
Problem occurred on version 1.63 and hence I have re-imaged to latest version and retest. I just select several songs and loop to play. The log file isn’t that long. I capture the log once music stopped.
You need to get the logs just before and those after the problem occurs (otherwise you miss relevant messages). You can look at the logs before those you give by typing ‘sudo journalctl -b’ and finding the appropriate time.
Following log was taken when the music stopped. Any help?
May 23 23:01:53 volumio volumio[1190]: info: [1495580513732] ControllerMpd::pushState
May 23 23:01:53 volumio volumio[1190]: info: CoreCommandRouter::servicePushState
May 23 23:01:53 volumio volumio[1190]: info: [1495580513733] CoreStateMachine::syncState
May 23 23:01:53 volumio volumio[1190]: info: [1495580513733] CorePlayQueue::getTrack 0
May 23 23:01:53 volumio volumio[1190]: 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}
May 23 23:01:53 volumio volumio[1190]: info: CURRENT POSITION 0
May 23 23:01:53 volumio volumio[1190]: info: [1495580513756] CoreStateMachine::syncState stateService stop
May 23 23:01:53 volumio volumio[1190]: info: [1495580513757] CoreStateMachine::syncState currentStatus play
May 23 23:01:53 volumio volumio[1190]: info: CURRENT POSITION 0
May 23 23:01:53 volumio volumio[1190]: info: CURRENT POSITION 0
May 23 23:01:53 volumio volumio[1190]: info: END OF QUEUE
May 23 23:01:53 volumio volumio[1190]: info: [1495580513769] CoreStateMachine::pushEmptyState
May 23 23:01:53 volumio volumio[1190]: info: CoreCommandRouter::volumioPushState
May 23 23:01:53 volumio volumio[1190]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
May 23 23:01:53 volumio volumio[1190]: info: [1495580513772]
Would you please post the entire log on pastebin.com/ or similar, because you’re not showing enough information That way, I can see the log in it’s entirety.
‘sudo journalctl -b > mylog.log’ and post the file ‘mylog.log’ (remove any sensitive information), or use the ‘send log’ feature in Volumio volumio.github.io/docs/User_Man … oting.html
Is it possible for you to try your setup without UPNP play in order to narrow things down a little?
Edit: problem reproduced with UPNP content from my phone (not a NAS problem). No errors shown in logs, but just before play stops Volumio repeatedly tries to “push” the file:
May 25 08:17:05 volumiokitchen volumio[905]: info: [1495700225279] InterfaceWebUI::pushState
May 25 08:17:05 volumiokitchen volumio[905]: info: [1495700225310] ------------------------------ 313ms
May 25 08:17:05 volumiokitchen volumio[905]: info: [1495700225312] ------------------------------ 311ms
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:17:05 volumiokitchen volumio[905]: info: Pushing Favourites {"service":"mpd","uri":"http://192.168.2.64:57645/external/audio/media/754.mp3","favourite":false}
May 25 08:20:18 volumiokitchen volumio[905]: info: Mpd Status Update: player
May 25 08:20:18 volumiokitchen volumio[905]: info:
May 25 08:20:18 volumiokitchen volumio[905]: [1495700418521] ---------------------------- MPD announces state update
then 3 minute gap, and Volumio apparently gives up (in my case started playing radio which was already in queue).