Feb 12 21:49:04 hifiberry volumio[1175]: info: CoreCommandRouter::volumioGetState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::volumioSeek Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::seek Feb 12 21:49:10 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:49:10 hifiberry volumio[1175]: info: TRACKBLOCK {"service":"jellyfin","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=8538ea114830728715c153b7ee31ae9b","albumart":"http://192.168.68.52:8096/Items/8538ea114830728715c153b7ee31ae9b/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"Daft Punk","album":"Discovery","name":"Digital Love","title":"Digital Love","bitdepth":"16 bit","samplerate":"44.1 kHz","duration":298,"trackType":"flac","channels":2} Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:10 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:10 hifiberry volumio[1175]: info: ControllerMpd::seek Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:10 hifiberry volumio[1175]: error: null Feb 12 21:49:10 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:10 hifiberry volumio[1175]: info: Feb 12 21:49:10 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:10 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:10 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=8538ea114830728715c153b7ee31ae9b","favourite":false} Feb 12 21:49:10 hifiberry volumio[1175]: info: sendMpdCommand status took 13 milliseconds Feb 12 21:49:10 hifiberry volumio[1175]: info: sendMpdCommand status took 12 milliseconds Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:10 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 12 21:49:10 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:10 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:10 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:49:10 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":198458,"duration":298,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1146 Kbps","isStreaming":false,"title":"Digital Love","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/8538ea114830728715c153b7ee31ae9b/stream.flac?static=true&mediaSourceId=8538ea114830728715c153b7ee31ae9b&tag=43c55429cf896e1744746abce4e20038&t.flac","trackType":"flac"} Feb 12 21:49:10 hifiberry volumio[1175]: verbose: CURRENT POSITION 2 Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:10 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:10 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:10 hifiberry volumio[1175]: info: ------------------------------ 51ms Feb 12 21:49:10 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:10 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Digital Love (at 198458 ms) Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::volumioNext Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::next Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:14 hifiberry volumio[1175]: info: ControllerMpd::next Feb 12 21:49:14 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand next Feb 12 21:49:14 hifiberry volumio[1175]: info: Feb 12 21:49:14 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:14 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:14 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:14 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:14 hifiberry volumio[1175]: info: Feb 12 21:49:14 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:14 hifiberry volumio[1175]: info: sendMpdCommand next took 16 milliseconds Feb 12 21:49:14 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:14 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:14 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:49:14 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:49:14 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:49:14 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:14 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:14 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:14 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:49:14 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 21:49:14 hifiberry volumio[1175]: verbose: CURRENT POSITION 2 Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:14 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:14 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=d0ba3a56efe1c1ac781fffbc484d3ebe Feb 12 21:49:14 hifiberry volumio[1175]: info: ------------------------------ 19ms Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:14 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:14 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:14 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=8538ea114830728715c153b7ee31ae9b","favourite":false} Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: Digital Love (at 203158 ms) Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for Harder, Better, Faster, Stronger: http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac" Feb 12 21:49:15 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 305ms Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac" Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac" took 1 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand play took 2 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song Harder, Better, Faster, Stronger as played by jellyfin. Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:15 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 16 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 17 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:15 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Harder, Better, Faster, Stronger","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac","trackType":"flac"} Feb 12 21:49:15 hifiberry volumio[1175]: verbose: CURRENT POSITION 3 Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 33ms Feb 12 21:49:15 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:15 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Harder, Better, Faster, Stronger","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac","trackType":"flac"} Feb 12 21:49:15 hifiberry volumio[1175]: verbose: CURRENT POSITION 3 Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:15 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 39ms Feb 12 21:49:15 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: Harder, Better, Faster, Stronger (at 0 ms) Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: Feb 12 21:49:15 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 5ms Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Harder, Better, Faster, Stronger (at 0 ms) Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:15 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:15 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"684 Kbps","isStreaming":false,"title":"Harder, Better, Faster, Stronger","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac","trackType":"flac"} Feb 12 21:49:15 hifiberry volumio[1175]: verbose: CURRENT POSITION 3 Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:15 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:15 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:15 hifiberry volumio[1175]: info: ------------------------------ 23ms Feb 12 21:49:15 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:15 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Harder, Better, Faster, Stronger (at 962 ms) Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::volumioSeek Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::seek Feb 12 21:49:18 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:18 hifiberry volumio[1175]: info: TRACKBLOCK {"service":"jellyfin","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=d0ba3a56efe1c1ac781fffbc484d3ebe","albumart":"http://192.168.68.52:8096/Items/d0ba3a56efe1c1ac781fffbc484d3ebe/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"Daft Punk","album":"Discovery","name":"Harder, Better, Faster, Stronger","title":"Harder, Better, Faster, Stronger","bitdepth":"16 bit","samplerate":"44.1 kHz","duration":224,"trackType":"flac"} Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:18 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:18 hifiberry volumio[1175]: info: ControllerMpd::seek Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:18 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=d0ba3a56efe1c1ac781fffbc484d3ebe","favourite":false} Feb 12 21:49:18 hifiberry volumio[1175]: error: null Feb 12 21:49:18 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:18 hifiberry volumio[1175]: info: Feb 12 21:49:18 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:18 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:18 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:18 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:49:18 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:18 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:18 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 12 21:49:18 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:18 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:18 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":83000,"duration":224,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"714 Kbps","isStreaming":false,"title":"Harder, Better, Faster, Stronger","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac","trackType":"flac"} Feb 12 21:49:18 hifiberry volumio[1175]: verbose: CURRENT POSITION 3 Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:18 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:18 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:18 hifiberry volumio[1175]: info: ------------------------------ 27ms Feb 12 21:49:18 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Harder, Better, Faster, Stronger (at 83000 ms) Feb 12 21:49:22 hifiberry volumio[1175]: info: [jellyfin-poller] Polled http://192.168.178.92:8096/: offline Feb 12 21:49:33 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::ClearQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::stPlaybackTimer Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::serviceStop Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::serviceStop Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:33 hifiberry volumio[1175]: info: ControllerMpd::stop Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::clearPlayQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:49:33 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:33 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56 Feb 12 21:49:33 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56 in service jellyfin Feb 12 21:49:33 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56 Feb 12 21:49:33 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"50f4a4f71c524ddaba98dd3e9bb1e2bb","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"286368e0bf3d5df66424f62f384d6d56","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Feb 12 21:49:33 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:33 hifiberry volumio[1175]: info: Feb 12 21:49:33 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand stop took 22 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:33 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:49:33 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Harder, Better, Faster, Stronger","artist":"Daft Punk","album":"Discovery","uri":"http://192.168.68.52:8096/Audio/d0ba3a56efe1c1ac781fffbc484d3ebe/stream.flac?static=true&mediaSourceId=d0ba3a56efe1c1ac781fffbc484d3ebe&tag=e94b5b9b32dabe483a15901f6da449ea&t.flac","trackType":"flac"} Feb 12 21:49:33 hifiberry volumio[1175]: verbose: CURRENT POSITION 3 Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:33 hifiberry volumio[1175]: info: No code Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:33 hifiberry volumio[1175]: info: ------------------------------ 24ms Feb 12 21:49:33 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:33 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=d0ba3a56efe1c1ac781fffbc484d3ebe","favourite":false} Feb 12 21:49:33 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.68.52:8096/Items?userId=50f4a4f71c524ddaba98dd3e9bb1e2bb&startIndex=0&recursive=true&sortOrder=Ascending&parentId=286368e0bf3d5df66424f62f384d6d56&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPlay Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::play index 0 Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:33 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:33 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:33 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:33 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: Harder, Better, Faster, Stronger (at 98319 ms) Feb 12 21:49:33 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for Call My Phone Thinking I'm Doing Nothing Better: http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8 Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:49:33 hifiberry volumio[1175]: info: Feb 12 21:49:33 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:33 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:33 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:49:33 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac" Feb 12 21:49:33 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:49:33 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac" Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac" took 1 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:34 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:34 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:34 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:34 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 4ms Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 4ms Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand play took 2 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song Call My Phone Thinking I'm Doing Nothing Better as played by jellyfin. Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:34 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 17 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 17 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 4 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:34 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Call My Phone Thinking I'm Doing Nothing Better","artist":"Streets, The, Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac","trackType":"flac"} Feb 12 21:49:34 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 29ms Feb 12 21:49:34 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:34 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Call My Phone Thinking I'm Doing Nothing Better","artist":"Streets, The, Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac","trackType":"flac"} Feb 12 21:49:34 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:34 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 40ms Feb 12 21:49:34 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:34 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: info: Feb 12 21:49:34 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 9ms Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:34 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:34 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"806 Kbps","isStreaming":false,"title":"Call My Phone Thinking I'm Doing Nothing Better","artist":"Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac","trackType":"flac"} Feb 12 21:49:34 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:34 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:34 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:34 hifiberry volumio[1175]: info: ------------------------------ 34ms Feb 12 21:49:34 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:34 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: Call My Phone Thinking I'm Doing Nothing Better (at 0 ms) Feb 12 21:49:34 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Call My Phone Thinking I'm Doing Nothing Better (at 0 ms) Feb 12 21:49:34 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Call My Phone Thinking I'm Doing Nothing Better (at 962 ms) Feb 12 21:49:36 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Feb 12 21:49:36 hifiberry volumio[1175]: info: [jellyfin-browse] browseUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56 Feb 12 21:49:36 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"50f4a4f71c524ddaba98dd3e9bb1e2bb","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"286368e0bf3d5df66424f62f384d6d56","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Feb 12 21:49:36 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.68.52:8096/Items?userId=50f4a4f71c524ddaba98dd3e9bb1e2bb&startIndex=0&recursive=true&sortOrder=Ascending&parentId=286368e0bf3d5df66424f62f384d6d56&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 12 21:49:37 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preloading song: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 Feb 12 21:49:37 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:37 hifiberry volumio[1175]: info: No valid Plugin REST Endpoint Feb 12 21:49:37 hifiberry volumio[1175]: info: No valid Plugin REST Endpoint Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::volumioSeek Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::seek Feb 12 21:49:42 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:42 hifiberry volumio[1175]: info: TRACKBLOCK {"service":"jellyfin","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=9f7641990ecf671e847b85996db3b16b","albumart":"http://192.168.68.52:8096/Items/9f7641990ecf671e847b85996db3b16b/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"Streets, The, Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","name":"Call My Phone Thinking I'm Doing Nothing Better","title":"Call My Phone Thinking I'm Doing Nothing Better","bitdepth":"16 bit","samplerate":"44.1 kHz","duration":172,"trackType":"flac"} Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:42 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:42 hifiberry volumio[1175]: info: ControllerMpd::seek Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:42 hifiberry volumio[1175]: error: null Feb 12 21:49:42 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:42 hifiberry volumio[1175]: info: Feb 12 21:49:42 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:42 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:42 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=9f7641990ecf671e847b85996db3b16b","favourite":false} Feb 12 21:49:42 hifiberry volumio[1175]: info: sendMpdCommand status took 15 milliseconds Feb 12 21:49:42 hifiberry volumio[1175]: info: sendMpdCommand status took 13 milliseconds Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:42 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:42 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:42 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:42 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:42 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":35458,"duration":172,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1315 Kbps","isStreaming":false,"title":"Call My Phone Thinking I'm Doing Nothing Better","artist":"Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac","trackType":"flac"} Feb 12 21:49:42 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:42 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:42 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:42 hifiberry volumio[1175]: info: ------------------------------ 38ms Feb 12 21:49:42 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:42 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Call My Phone Thinking I'm Doing Nothing Better (at 35458 ms) Feb 12 21:49:47 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::ClearQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::stPlaybackTimer Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::serviceStop Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::serviceStop Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::stop Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::clearPlayQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:49:47 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand stop took 23 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 4 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:49:47 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Call My Phone Thinking I'm Doing Nothing Better","artist":"Tame Impala","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/9f7641990ecf671e847b85996db3b16b/stream.flac?static=true&mediaSourceId=9f7641990ecf671e847b85996db3b16b&tag=6f1d4c1d004d4302231b3c31a03837f8&t.flac","trackType":"flac"} Feb 12 21:49:47 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: No code Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 28ms Feb 12 21:49:47 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:47 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=9f7641990ecf671e847b85996db3b16b","favourite":false} Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: Call My Phone Thinking I'm Doing Nothing Better (at 40283 ms) Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPlay Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::play index 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:49:47 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 Feb 12 21:49:47 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 Feb 12 21:49:47 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 in service jellyfin Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for None Of Us Are Getting Out Of This Life Alive: http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand stop took 4 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:47 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand clear took 2 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac" Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:47 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 75ms Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac" Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:47 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac" took 1 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:47 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:47 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:47 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand play took 2 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song None Of Us Are Getting Out Of This Life Alive as played by jellyfin. Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:47 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 14 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 14 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:47 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"None Of Us Are Getting Out Of This Life Alive","artist":"Streets, The, Idles","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac","trackType":"flac"} Feb 12 21:49:47 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 26ms Feb 12 21:49:47 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:47 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"None Of Us Are Getting Out Of This Life Alive","artist":"Streets, The, Idles","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac","trackType":"flac"} Feb 12 21:49:47 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:47 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 38ms Feb 12 21:49:47 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:47 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: Feb 12 21:49:47 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: None Of Us Are Getting Out Of This Life Alive (at 0 ms) Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: None Of Us Are Getting Out Of This Life Alive (at 0 ms) Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 8ms Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:47 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":235,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"905 Kbps","isStreaming":false,"title":"None Of Us Are Getting Out Of This Life Alive","artist":"Idles","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/ff02035448bd4ad99f4d6d61cf743e93/stream.flac?static=true&mediaSourceId=ff02035448bd4ad99f4d6d61cf743e93&tag=7b27b0e9baf512e574693ee817ac759b&t.flac","trackType":"flac"} Feb 12 21:49:47 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:47 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:47 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:47 hifiberry volumio[1175]: info: ------------------------------ 23ms Feb 12 21:49:47 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:47 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: None Of Us Are Getting Out Of This Life Alive (at 962 ms) Feb 12 21:49:53 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::ClearQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::stPlaybackTimer Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::serviceStop Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::serviceStop Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::stop Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::clearPlayQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:49:53 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=9f7641990ecf671e847b85996db3b16b Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=ff02035448bd4ad99f4d6d61cf743e93 Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=83e6f5f046284a8f064a61d2364a705d Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=f8830fa8984e6af4804eabd47cfb4e23 Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fc88ec9d171c4c77146e4ea01f2e93e4 Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=fe6b109dfa488ae0d2539e6bda049a3d Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=3b58943012fb8148e02b4e1ce52c8457 Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=84feae0402ed9baa91145dc5e823dbed Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPlay Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::play index 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:49:53 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=d8e8790c13d63191ae6dfde37d6f2e5a Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=75e65a512dc9a2a3c7c636094de22d24 Feb 12 21:49:53 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 Feb 12 21:49:53 hifiberry volumio[1175]: info: Using cached record of: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=286368e0bf3d5df66424f62f384d6d56/song@songId=dad789e48fe0c745a9894377160b8f63 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=c3d5b37584b43cfcfc0a4714feea84e2 Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand stop took 41 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 18ms Feb 12 21:49:53 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:53 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: None Of Us Are Getting Out Of This Life Alive (at 6725 ms) Feb 12 21:49:53 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for Same Direction: http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41 Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:53 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac" Feb 12 21:49:53 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac" Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:53 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac" took 2 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:53 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:53 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:53 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand play took 1 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song Same Direction as played by jellyfin. Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:49:53 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:49:53 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: Feb 12 21:49:53 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 10 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 10 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Same Direction","artist":"Streets, The, Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac","trackType":"flac"} Feb 12 21:49:53 hifiberry volumio[1175]: verbose: CURRENT POSITION 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 22ms Feb 12 21:49:53 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:53 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Same Direction","artist":"Streets, The, Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac","trackType":"flac"} Feb 12 21:49:53 hifiberry volumio[1175]: verbose: CURRENT POSITION 8 Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:53 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:53 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:53 hifiberry volumio[1175]: info: ------------------------------ 32ms Feb 12 21:49:53 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:54 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: Same Direction (at 0 ms) Feb 12 21:49:54 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Same Direction (at 0 ms) Feb 12 21:49:54 hifiberry volumio[1175]: info: Feb 12 21:49:54 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:49:54 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:49:54 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:54 hifiberry volumio[1175]: info: Feb 12 21:49:54 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:54 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:54 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:54 hifiberry volumio[1175]: info: ------------------------------ 5ms Feb 12 21:49:54 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:54 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 12 21:49:54 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:54 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:54 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:54 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:54 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":962,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1218 Kbps","isStreaming":false,"title":"Same Direction","artist":"Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac","trackType":"flac"} Feb 12 21:49:54 hifiberry volumio[1175]: verbose: CURRENT POSITION 8 Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:54 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:54 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:54 hifiberry volumio[1175]: info: ------------------------------ 30ms Feb 12 21:49:54 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:49:54 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Same Direction (at 962 ms) Feb 12 21:49:55 hifiberry volumio[1175]: info: [jellyfin-poller] Polled http://192.168.178.92:8096/: offline Feb 12 21:49:56 hifiberry volumio[1175]: verbose: New Socket.io Connection to 192.168.68.63:3000 from 192.168.68.62 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Feb 12 21:49:56 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 12 21:49:56 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 12 21:49:56 hifiberry volumio[1175]: info: Discovery: Getting this device information Feb 12 21:49:56 hifiberry volumio[1175]: info: CoreCommandRouter::volumioGetState Feb 12 21:49:56 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::volumioSeek Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::seek Feb 12 21:49:58 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:58 hifiberry volumio[1175]: info: TRACKBLOCK {"service":"jellyfin","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=c3d5b37584b43cfcfc0a4714feea84e2","albumart":"http://192.168.68.52:8096/Items/c3d5b37584b43cfcfc0a4714feea84e2/Images/Primary?maxWidth=500&maxHeight=500&quality=90","artist":"Streets, The, Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","name":"Same Direction","title":"Same Direction","bitdepth":"16 bit","samplerate":"44.1 kHz","duration":196,"trackType":"flac"} Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:49:58 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:49:58 hifiberry volumio[1175]: info: ControllerMpd::seek Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:58 hifiberry volumio[1175]: error: null Feb 12 21:49:58 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:58 hifiberry volumio[1175]: info: Feb 12 21:49:58 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:49:58 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:49:58 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=c3d5b37584b43cfcfc0a4714feea84e2","favourite":false} Feb 12 21:49:58 hifiberry volumio[1175]: info: sendMpdCommand status took 12 milliseconds Feb 12 21:49:58 hifiberry volumio[1175]: info: sendMpdCommand status took 8 milliseconds Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:49:58 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 21:49:58 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:58 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:49:58 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:49:58 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":50458,"duration":197,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1169 Kbps","isStreaming":false,"title":"Same Direction","artist":"Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac","trackType":"flac"} Feb 12 21:49:58 hifiberry volumio[1175]: verbose: CURRENT POSITION 8 Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:49:58 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:49:58 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:49:58 hifiberry volumio[1175]: info: ------------------------------ 37ms Feb 12 21:49:58 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:01 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Same Direction (at 50458 ms) Feb 12 21:50:05 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::ClearQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::stPlaybackTimer Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::serviceStop Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::serviceStop Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:50:05 hifiberry volumio[1175]: info: ControllerMpd::stop Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::clearPlayQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::addQueueItems Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::addQueueItems Feb 12 21:50:05 hifiberry volumio[1175]: info: Preload queue cleared Feb 12 21:50:05 hifiberry volumio[1175]: info: Adding Item to queue: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=2e180cb283f42ac3ec8fd1fcf7b0f414 Feb 12 21:50:05 hifiberry volumio[1175]: info: Exploding uri jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=2e180cb283f42ac3ec8fd1fcf7b0f414 in service jellyfin Feb 12 21:50:05 hifiberry volumio[1175]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/songs@albumId=2e180cb283f42ac3ec8fd1fcf7b0f414 Feb 12 21:50:05 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"50f4a4f71c524ddaba98dd3e9bb1e2bb","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"2e180cb283f42ac3ec8fd1fcf7b0f414","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Feb 12 21:50:05 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:05 hifiberry volumio[1175]: info: Feb 12 21:50:05 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand stop took 24 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:05 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 8 Feb 12 21:50:05 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Same Direction","artist":"Jimothy Lacoste","album":"None Of Us Are Getting Out Of This Life Alive","uri":"http://192.168.68.52:8096/Audio/c3d5b37584b43cfcfc0a4714feea84e2/stream.flac?static=true&mediaSourceId=c3d5b37584b43cfcfc0a4714feea84e2&tag=7b3f0962222200b9660d8025e9ae8a41&t.flac","trackType":"flac"} Feb 12 21:50:05 hifiberry volumio[1175]: verbose: CURRENT POSITION 8 Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:05 hifiberry volumio[1175]: info: No code Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:05 hifiberry volumio[1175]: info: ------------------------------ 21ms Feb 12 21:50:05 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:05 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=c3d5b37584b43cfcfc0a4714feea84e2","favourite":false} Feb 12 21:50:05 hifiberry volumio[1175]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.68.52:8096/Items?userId=50f4a4f71c524ddaba98dd3e9bb1e2bb&startIndex=0&recursive=true&sortOrder=Ascending&parentId=2e180cb283f42ac3ec8fd1fcf7b0f414&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::saveQueue Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPlay Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::play index 0 Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:50:05 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:05 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=523bee8a040e3d0f01346b7336ee024f Feb 12 21:50:05 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: Same Direction (at 57050 ms) Feb 12 21:50:05 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for Disloyal Order Of Water Buffaloes: http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17 Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:50:05 hifiberry volumio[1175]: info: Feb 12 21:50:05 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:05 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac" Feb 12 21:50:05 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:05 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac" Feb 12 21:50:05 hifiberry volumio[1175]: info: Feb 12 21:50:05 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:05 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac" took 2 milliseconds Feb 12 21:50:05 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:05 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:05 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:05 hifiberry volumio[1175]: info: Feb 12 21:50:05 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:05 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:05 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:50:05 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:50:05 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:50:05 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:50:05 hifiberry volumio[1175]: info: sendMpdCommand play took 2 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song Disloyal Order Of Water Buffaloes as played by jellyfin. Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: Feb 12 21:50:06 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:50:06 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:50:06 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: Feb 12 21:50:06 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 13 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 12 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 4 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:06 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Disloyal Order Of Water Buffaloes","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac","trackType":"flac"} Feb 12 21:50:06 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:50:06 hifiberry volumio[1175]: info: ------------------------------ 21ms Feb 12 21:50:06 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 9 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:06 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Disloyal Order Of Water Buffaloes","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac","trackType":"flac"} Feb 12 21:50:06 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:06 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:06 hifiberry volumio[1175]: info: ------------------------------ 35ms Feb 12 21:50:06 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:06 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: Disloyal Order Of Water Buffaloes (at 0 ms) Feb 12 21:50:06 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Disloyal Order Of Water Buffaloes (at 0 ms) Feb 12 21:50:06 hifiberry volumio[1175]: info: Feb 12 21:50:06 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:06 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: Feb 12 21:50:06 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 9 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: info: ------------------------------ 14ms Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand status took 8 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:06 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 12 21:50:06 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:06 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1100,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"532 Kbps","isStreaming":false,"title":"Disloyal Order Of Water Buffaloes","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/523bee8a040e3d0f01346b7336ee024f/stream.flac?static=true&mediaSourceId=523bee8a040e3d0f01346b7336ee024f&tag=a2816d5d8dfb3320bfe2cef9f3e79a17&t.flac","trackType":"flac"} Feb 12 21:50:06 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:06 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:06 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:06 hifiberry volumio[1175]: info: ------------------------------ 55ms Feb 12 21:50:06 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: Disloyal Order Of Water Buffaloes (at 1100 ms) Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioNext Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::next Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::next Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand next Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:09 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand next took 3 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 4ms Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 0 Feb 12 21:50:09 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 21:50:09 hifiberry volumio[1175]: verbose: CURRENT POSITION 0 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=4339fc1eae336d0110eafd79780f9180 Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 19ms Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=523bee8a040e3d0f01346b7336ee024f","favourite":false} Feb 12 21:50:09 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: Disloyal Order Of Water Buffaloes (at 4199 ms) Feb 12 21:50:09 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for I Don't Care: http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87 Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:09 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac" Feb 12 21:50:09 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 76ms Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac" Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:09 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac" took 6 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:09 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:09 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:09 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 4ms Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand play Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand play took 3 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: [jellyfin-play]: Mark song I Don't Care as played by jellyfin. Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 12 21:50:09 hifiberry volumio[1175]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 12 21:50:09 hifiberry volumio[1175]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 10 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 10 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 6 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 5 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Don't Care","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac","trackType":"flac"} Feb 12 21:50:09 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus stop Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 21ms Feb 12 21:50:09 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Don't Care","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac","trackType":"flac"} Feb 12 21:50:09 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:09 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 30ms Feb 12 21:50:09 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:09 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: Feb 12 21:50:09 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 4 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 5ms Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand status took 2 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 1 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: verbose: ControllerMpd::parseTrackInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:09 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1008,"duration":214,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1118 Kbps","isStreaming":false,"title":"I Don't Care","artist":"Fall Out Boy","album":"Folie à Deux","uri":"http://192.168.68.52:8096/Audio/4339fc1eae336d0110eafd79780f9180/stream.flac?static=true&mediaSourceId=4339fc1eae336d0110eafd79780f9180&tag=60d67ae8ad019bb8da29f9b7a6df7e87&t.flac","trackType":"flac"} Feb 12 21:50:09 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService play Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:09 hifiberry volumio[1175]: info: Received an update from plugin. extracting info from payload Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:09 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:09 hifiberry volumio[1175]: info: ------------------------------ 19ms Feb 12 21:50:09 hifiberry volumio[1175]: info: Pushing Favourites {"favourite":false} Feb 12 21:50:10 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'start' for song: I Don't Care (at 0 ms) Feb 12 21:50:10 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: I Don't Care (at 0 ms) Feb 12 21:50:10 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'timeupdate' for song: I Don't Care (at 962 ms) Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::volumioNext Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::next Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 21:50:11 hifiberry volumio[1175]: info: ControllerMpd::next Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand next Feb 12 21:50:11 hifiberry volumio[1175]: info: Feb 12 21:50:11 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:11 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:11 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:11 hifiberry volumio[1175]: info: Feb 12 21:50:11 hifiberry volumio[1175]: ---------------------------- MPD announces state update: player Feb 12 21:50:11 hifiberry volumio[1175]: info: sendMpdCommand next took 5 milliseconds Feb 12 21:50:11 hifiberry volumio[1175]: info: ControllerMpd::getState Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand status Feb 12 21:50:11 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:11 hifiberry volumio[1175]: info: sendMpdCommand status took 3 milliseconds Feb 12 21:50:11 hifiberry volumio[1175]: info: ------------------------------ 3ms Feb 12 21:50:11 hifiberry volumio[1175]: info: sendMpdCommand status took 1 milliseconds Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::parseState Feb 12 21:50:11 hifiberry volumio[1175]: info: ControllerMpd::pushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::servicePushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 1 Feb 12 21:50:11 hifiberry volumio[1175]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 21:50:11 hifiberry volumio[1175]: verbose: CURRENT POSITION 1 Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::syncState stateService stop Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::syncState currentStatus play Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:50:11 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:50:11 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=f2a4426847b1463f630bc16ed2a22bd5 Feb 12 21:50:11 hifiberry volumio[1175]: info: ------------------------------ 14ms Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreStateMachine::pushState Feb 12 21:50:11 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 2 Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 21:50:11 hifiberry volumio[1175]: info: CoreCommandRouter::volumioPushState Feb 12 21:50:11 hifiberry volumio[1175]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=4339fc1eae336d0110eafd79780f9180","favourite":false} Feb 12 21:50:11 hifiberry volumio[1175]: info: [jellyfin-play]: Reported 'stop' for song: I Don't Care (at 2469 ms) Feb 12 21:50:11 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for She's My Winona: http://192.168.68.52:8096/Audio/f2a4426847b1463f630bc16ed2a22bd5/stream.flac?static=true&mediaSourceId=f2a4426847b1463f630bc16ed2a22bd5&tag=9807b8d294ba26e223581641f6cace16 Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:50:11 hifiberry volumio[1175]: info: sendMpdCommand stop took 1 milliseconds Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:50:11 hifiberry volumio[1175]: info: Feb 12 21:50:11 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:11 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:11 hifiberry volumio[1175]: info: sendMpdCommand clear took 1 milliseconds Feb 12 21:50:11 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/f2a4426847b1463f630bc16ed2a22bd5/stream.flac?static=true&mediaSourceId=f2a4426847b1463f630bc16ed2a22bd5&tag=9807b8d294ba26e223581641f6cace16&t.flac" Feb 12 21:50:11 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:11 hifiberry volumio[1175]: info: ------------------------------ 2ms Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreCommandRouter::volumioNext Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::next Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::stop Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::play index undefined Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 21:50:13 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::startPlaybackTimer Feb 12 21:50:13 hifiberry volumio[1175]: info: CorePlayQueue::getTrack 3 Feb 12 21:50:13 hifiberry volumio[1175]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@c22cecd50735416a987bd06c10ab895c/song@songId=cc5158a57a9b580e3d6abe93a399609d Feb 12 21:50:13 hifiberry volumio[1175]: info: CoreStateMachine::updateTrackBlock Feb 12 21:50:13 hifiberry volumio[1175]: info: CorePlayQueue::getTrackBlock Feb 12 21:50:13 hifiberry volumio[1175]: info: [jellyfin-play] Stream URL for America's Suitehearts: http://192.168.68.52:8096/Audio/cc5158a57a9b580e3d6abe93a399609d/stream.flac?static=true&mediaSourceId=cc5158a57a9b580e3d6abe93a399609d&tag=2d20bbecd68289390b75ca10ea0fde76 Feb 12 21:50:13 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 21:50:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/f2a4426847b1463f630bc16ed2a22bd5/stream.flac?static=true&mediaSourceId=f2a4426847b1463f630bc16ed2a22bd5&tag=9807b8d294ba26e223581641f6cace16&t.flac" Feb 12 21:50:15 hifiberry volumio[1175]: info: sendMpdCommand stop took 1679 milliseconds Feb 12 21:50:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 21:50:15 hifiberry volumio[1175]: info: Feb 12 21:50:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:15 hifiberry volumio[1175]: info: Feb 12 21:50:15 hifiberry volumio[1175]: ---------------------------- MPD announces system playlist update Feb 12 21:50:15 hifiberry volumio[1175]: info: Ignoring MPD Status Update Feb 12 21:50:15 hifiberry volumio[1175]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/f2a4426847b1463f630bc16ed2a22bd5/stream.flac?static=true&mediaSourceId=f2a4426847b1463f630bc16ed2a22bd5&tag=9807b8d294ba26e223581641f6cace16&t.flac" took 4 milliseconds Feb 12 21:50:15 hifiberry volumio[1175]: info: sendMpdCommand clear took 3 milliseconds Feb 12 21:50:15 hifiberry volumio[1175]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/cc5158a57a9b580e3d6abe93a399609d/stream.flac?static=true&mediaSourceId=cc5158a57a9b580e3d6abe93a399609d&tag=2d20bbecd68289390b75ca10ea0fde76&t.flac" Feb 12 21:50:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:15 hifiberry volumio[1175]: verbose: MPD COMMAND [object Object] Feb 12 21:50:15 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:15 hifiberry volumio[1175]: error: updateQueue error: null Feb 12 21:50:15 hifiberry volumio[1175]: info: ------------------------------ 5ms Feb 12 21:50:15 hifiberry volumio[1175]: info: ------------------------------ 5ms Feb 12 21:50:15 hifiberry volumio[1175]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:50:15 hifiberry volumio[1175]: Error: [50@0] {addtagid} No such song Feb 12 21:50:15 hifiberry volumio[1175]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Feb 12 21:50:15 hifiberry volumio[1175]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Feb 12 21:50:15 hifiberry volumio[1175]: at Socket.emit (node:events:514:28) Feb 12 21:50:15 hifiberry volumio[1175]: at addChunk (node:internal/streams/readable:343:12) Feb 12 21:50:15 hifiberry volumio[1175]: at readableAddChunk (node:internal/streams/readable:312:11) Feb 12 21:50:15 hifiberry volumio[1175]: at Readable.push (node:internal/streams/readable:253:10) Feb 12 21:50:15 hifiberry volumio[1175]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Feb 12 21:50:15 hifiberry volumio[1175]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 21:50:16 hifiberry sudo[12011]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 21:49' Feb 12 21:50:16 hifiberry sudo[12011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"