RPI4
Volumio 3.046
Qobuz die while playing. Seems to occur at the end of the track (few seconds)
log : http://logs.volumio.org/volumiobuster/8J0jp9o.html
edit : log are not working?
edit2 : Qobuz Very instable
Feb 27 16:04:20 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 10
Feb 27 16:04:20 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:20 volumio-buster volumio[698]: info: Prefetching next song
Feb 27 16:04:20 volumio-buster volumio[698]: info: [1614441860051] ControllerQobuz::prefetch
Feb 27 16:04:21 volumio-buster volumio[698]: info: getStreamUrl took 1422 milliseconds
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand load "http://streaming-std.qobuz.com:80/file?uid=376682&eid=750966&fmt=6&profile=raw&app_id=539451548&cid=1005687&etsp=1614445461&hmac=gJ9bJg7NVkVfk8zRBUxesJ9omco"
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand add "http://streaming-std.qobuz.com:80/file?uid=376682&eid=750966&fmt=6&profile=raw&app_id=539451548&cid=1005687&etsp=1614445461&hmac=gJ9bJg7NVkVfk8zRBUxesJ9omco"
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand consume 1
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces system playlist update
Feb 27 16:04:21 volumio-buster volumio[698]: info: Ignoring MPD Status Update
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces state update: options
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::getState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand status
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces system playlist update
Feb 27 16:04:21 volumio-buster volumio[698]: info: Ignoring MPD Status Update
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces system playlist update
Feb 27 16:04:21 volumio-buster volumio[698]: info: Ignoring MPD Status Update
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 2ms
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 5ms
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 5ms
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseTrackInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::servicePushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::startPlaybackTimer
Feb 27 16:04:21 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:21 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173240,"duration":177,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1095 Kbps","isStreaming":false,"title":"file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","artist":null,"album":null,"uri":"http://streaming-std.qobuz.com:80/file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","trackType":"qobuz"}
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: CURRENT POSITION 11
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState stateService play
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState currentStatus play
Feb 27 16:04:21 volumio-buster volumio[698]: info: Received an update from plugin. extracting info from payload
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 16ms
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces state update: options
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::getState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand status
Feb 27 16:04:21 volumio-buster volumio[698]: info:
Feb 27 16:04:21 volumio-buster volumio[698]: ---------------------------- MPD announces state update: options
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::getState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand status
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseState
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseTrackInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::servicePushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173240,"duration":177,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1095 Kbps","isStreaming":false,"title":"file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","artist":null,"album":null,"uri":"http://streaming-std.qobuz.com:80/file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","trackType":"qobuz"}
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: CURRENT POSITION 11
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState stateService play
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState currentStatus play
Feb 27 16:04:21 volumio-buster volumio[698]: info: Received an update from plugin. extracting info from payload
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 16ms
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: ControllerMpd::parseTrackInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ControllerMpd::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::servicePushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: STATE SERVICE {"status":"play","position":0,"seek":173240,"duration":177,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1095 Kbps","isStreaming":false,"title":"file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","artist":null,"album":null,"uri":"http://streaming-std.qobuz.com:80/file?uid=376682&eid=14158551&fmt=7&profile=raw&app_id=539451548&cid=1005687&etsp=1614445375&hmac=PfFipNRxmMaOqb-_n-nUXeLJZ94","trackType":"qobuz"}
Feb 27 16:04:21 volumio-buster volumio[698]: verbose: CURRENT POSITION 11
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState stateService play
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::syncState currentStatus play
Feb 27 16:04:21 volumio-buster volumio[698]: info: Received an update from plugin. extracting info from payload
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreStateMachine::pushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPushState
Feb 27 16:04:21 volumio-buster volumio[698]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 27 16:04:21 volumio-buster volumio[698]: info: ------------------------------ 30ms
Feb 27 16:04:22 volumio-buster systemd[1]: mpd.service: Main process exited, code=killed, status=6/ABRT
Feb 27 16:04:22 volumio-buster systemd[1]: mpd.service: Failed with result 'signal'.
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreCommandRouter::volumioPause
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreStateMachine::pause
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreStateMachine::stPlaybackTimer
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreStateMachine::servicePause
Feb 27 16:04:32 volumio-buster volumio[698]: info: CorePlayQueue::getTrack 11
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreCommandRouter::servicePause
Feb 27 16:04:32 volumio-buster volumio[698]: info: [1614441872669] ControllerQobuz::pause
Feb 27 16:04:32 volumio-buster volumio[698]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 27 16:04:32 volumio-buster volumio[698]: info: ControllerMpd::pause
Feb 27 16:04:32 volumio-buster volumio[698]: verbose: ControllerMpd::sendMpdCommand pause
Feb 27 16:04:32 volumio-buster volumio[698]: error: MPD error: Error: This socket has been ended by the other party
Feb 27 16:04:32 volumio-buster volumio[698]: error: MPD error: Error: This socket has been ended by the other party
Feb 27 16:04:32 volumio-buster volumio[698]: error: MPD error: Error: This socket has been ended by the other party
Feb 27 16:04:37 volumio-buster systemd[1]: Starting Music Player Daemon...
Feb 27 16:04:37 volumio-buster volumio[698]: ------------------------------------ BT MESSAGE: BT STATUS: running
Feb 27 16:04:37 volumio-buster volumio[698]: BT PLUGIN MESSAGE: Playing: false
Feb 27 16:04:37 volumio-buster volumio[698]: BT PLUGIN MESSAGE: VOLATILE: undefined
Feb 27 16:04:37 volumio-buster volumio[698]: BT PLUGIN MESSAGE: STATE:undefined
Feb 27 16:04:38 volumio-buster mpd[1548]: Feb 27 16:04 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Feb 27 16:04:40 volumio-buster systemd[1]: Started Music Player Daemon.
Feb 27 16:04:40 volumio-buster volumio[698]: error: MPD error: The expression evaluated to a falsy value:
Feb 27 16:04:40 volumio-buster volumio[698]: assert.ok(self.idling)
Feb 27 16:04:40 volumio-buster volumio[698]: error: MPD error: The expression evaluated to a falsy value:
Feb 27 16:04:40 volumio-buster volumio[698]: assert.ok(self.idling)
Feb 27 16:04:40 volumio-buster volumio[698]: error: updateQueue error: null
Feb 27 16:04:45 volumio-buster volumio[698]: error: updateQueue error: null
Feb 27 16:04:45 volumio-buster volumio[698]: error: updateQueue error: null