Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=bc5558aa802faf116b565241dbfc1a87 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=9ec41a284545a5b93552afe7e9a2aaef Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=8fff397ca7798862ec637b45eec095f0 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=693142ba65eb844eb2f0e9d1db8df8dd Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=df72a713e211f9ef738c4636d9addaed Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=f27412c5a798c2b093dff5346c33dfdc Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=b1bd540333c095bb4313642605d1123e Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=010044b83799a7c61f247ecad89d9021 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=79ead53c8169aec41618c3a8a1ad3495 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=ded992a5a2841f0ab4a7cbf820676176 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=cbfc9b39ebf3095fb898eeac3a8018af Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=7eee234dd931ddee7afd55e22f66cb6b Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=2a41a2ec2128ffe384671d7f56dd0221 Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=d3f2dbd12add4e134da0bf2fcc2fe3ed Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=c317a39d9d4e3525ae663b63a8e9561d Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=5085de063d06e8c9dbea27400b568ded Dec 21 10:26:00 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:00 jayne-volumio volumio[11547]: info: No valid Plugin REST Endpoint Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::ClearQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::stPlaybackTimer Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::serviceStop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::serviceStop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::stop Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::clearPlayQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=bc5558aa802faf116b565241dbfc1a87 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=bc5558aa802faf116b565241dbfc1a87 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=bc5558aa802faf116b565241dbfc1a87 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPlay Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::play index 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=9ec41a284545a5b93552afe7e9a2aaef Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=9ec41a284545a5b93552afe7e9a2aaef in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=9ec41a284545a5b93552afe7e9a2aaef Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=8fff397ca7798862ec637b45eec095f0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=8fff397ca7798862ec637b45eec095f0 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=8fff397ca7798862ec637b45eec095f0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=693142ba65eb844eb2f0e9d1db8df8dd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=693142ba65eb844eb2f0e9d1db8df8dd in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=693142ba65eb844eb2f0e9d1db8df8dd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=df72a713e211f9ef738c4636d9addaed Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=df72a713e211f9ef738c4636d9addaed in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=df72a713e211f9ef738c4636d9addaed Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=f27412c5a798c2b093dff5346c33dfdc Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=f27412c5a798c2b093dff5346c33dfdc in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=f27412c5a798c2b093dff5346c33dfdc Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=b1bd540333c095bb4313642605d1123e Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=b1bd540333c095bb4313642605d1123e in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=b1bd540333c095bb4313642605d1123e Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=010044b83799a7c61f247ecad89d9021 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=010044b83799a7c61f247ecad89d9021 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=010044b83799a7c61f247ecad89d9021 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=79ead53c8169aec41618c3a8a1ad3495 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=79ead53c8169aec41618c3a8a1ad3495 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=79ead53c8169aec41618c3a8a1ad3495 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=ded992a5a2841f0ab4a7cbf820676176 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=ded992a5a2841f0ab4a7cbf820676176 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=ded992a5a2841f0ab4a7cbf820676176 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=cbfc9b39ebf3095fb898eeac3a8018af Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=cbfc9b39ebf3095fb898eeac3a8018af in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=cbfc9b39ebf3095fb898eeac3a8018af Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=7eee234dd931ddee7afd55e22f66cb6b Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=7eee234dd931ddee7afd55e22f66cb6b in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=7eee234dd931ddee7afd55e22f66cb6b Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=2a41a2ec2128ffe384671d7f56dd0221 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=2a41a2ec2128ffe384671d7f56dd0221 in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=2a41a2ec2128ffe384671d7f56dd0221 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=d3f2dbd12add4e134da0bf2fcc2fe3ed Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=d3f2dbd12add4e134da0bf2fcc2fe3ed in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=d3f2dbd12add4e134da0bf2fcc2fe3ed Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=c317a39d9d4e3525ae663b63a8e9561d Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=c317a39d9d4e3525ae663b63a8e9561d in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=c317a39d9d4e3525ae663b63a8e9561d Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=5085de063d06e8c9dbea27400b568ded Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=5085de063d06e8c9dbea27400b568ded in service jellyfin Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=ad602b6ed1bf23e92347c5c5d375b11c/song@songId=5085de063d06e8c9dbea27400b568ded Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::play index undefined Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/song@songId=bc5558aa802faf116b565241dbfc1a87 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 83 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 27ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 27ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 26ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play] Stream URL for A Heartbreak: http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 13 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'stop' for song: Solar System Trapeze (at 13380 ms) Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand clear took 7 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac" Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 8ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac" Dec 21 10:26:03 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:03 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 352ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 352ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand addid "http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac" took 1 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 4ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand play took 2 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 1ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play]: Mark song A Heartbreak as played by Jayne. Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 24 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 30 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 31 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 30 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 15 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 11 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 12ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 10 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 7ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand status took 6 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus stop Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":152,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1446 Kbps","isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 71ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 87ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 87ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Dec 21 10:26:03 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 58 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 59 milliseconds Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":731,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1952 Kbps","isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1933 Kbps","isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":994,"duration":256,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1933 Kbps","isStreaming":false,"title":"A Heartbreak","artist":"Angus & Julia Stone","album":"Angus & Julia Stone (Deluxe)","uri":"http://192.168.1.157:8096/Audio/bc5558aa802faf116b565241dbfc1a87/stream.flac?static=true&mediaSourceId=bc5558aa802faf116b565241dbfc1a87&tag=af4ef4e153a6cc8529acf2ac51d10b40&t.flac","trackType":"flac"} Dec 21 10:26:03 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 120ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 115ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: ------------------------------ 112ms Dec 21 10:26:03 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'start' for song: A Heartbreak (at 42 ms) Dec 21 10:26:03 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'timeupdate' for song: A Heartbreak (at 994 ms) Dec 21 10:26:33 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 21 10:26:33 jayne-volumio volumio[11547]: info: [jellyfin-browse] browseUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1 Dec 21 10:26:33 jayne-volumio volumio[11547]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"fc7dab59cad04f87ade955a71cc9c1fc","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"7aeb46bccb534316f935efe79d8be1a1","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Dec 21 10:26:33 jayne-volumio volumio[11547]: verbose: [jellyfin] getItemsFromAPI(): http://192.168.1.157:8096/Items?userId=fc7dab59cad04f87ade955a71cc9c1fc&startIndex=0&recursive=true&sortOrder=Ascending&parentId=7aeb46bccb534316f935efe79d8be1a1&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Preloading song: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef in service jellyfin Dec 21 10:26:33 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:33 jayne-volumio volumio[11547]: info: No valid Plugin REST Endpoint Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 in service jellyfin Dec 21 10:26:33 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 in service jellyfin Dec 21 10:26:33 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:33 jayne-volumio volumio[11547]: info: Exploding uri jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f in service jellyfin Dec 21 10:26:33 jayne-volumio volumio[11547]: info: [jellyfin-browse] explodeUri: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::ClearQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::stPlaybackTimer Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::serviceStop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::serviceStop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::stop Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::clearPlayQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPlay Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::play index 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::play index undefined Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 40 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 27ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 24ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 23ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play] Stream URL for Bad: http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 14 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'stop' for song: A Heartbreak (at 37273 ms) Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand clear took 2 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac" Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 329ms Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac" Dec 21 10:26:40 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:40 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 329ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 329ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand addid "http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac" took 1 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand play took 2 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 4ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play]: Mark song Bad as played by Jayne. Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 24 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 24 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 23 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 22 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 6 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 6ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1027 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus stop Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"989 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"989 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 48ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 69ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 69ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Dec 21 10:26:40 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 53 milliseconds Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 7ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 6ms Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1057 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1057 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":248,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1057 Kbps","isStreaming":false,"title":"Bad","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/86d9514b782a7a271db5acbe130ddaef/stream.flac?static=true&mediaSourceId=86d9514b782a7a271db5acbe130ddaef&tag=24cf2e2bec3766b6dea1903de52c5766&t.flac","trackType":"flac"} Dec 21 10:26:40 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 0 Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 99ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 96ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: ------------------------------ 95ms Dec 21 10:26:40 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'start' for song: Bad (at 848 ms) Dec 21 10:26:40 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'timeupdate' for song: Bad (at 848 ms) Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::ClearQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::stPlaybackTimer Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::serviceStop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 0 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::serviceStop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::stop Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::clearPlayQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPlay Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::play index 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::play index undefined Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 33 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ------------------------------ 20ms Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ------------------------------ 18ms Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:50 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ------------------------------ 31ms Dec 21 10:26:50 jayne-volumio volumio[11547]: info: [jellyfin-play] Stream URL for Smooth Criminal: http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121 Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 0 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:50 jayne-volumio volumio[11547]: info: sendMpdCommand clear took 0 milliseconds Dec 21 10:26:50 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac" Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Dec 21 10:26:50 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:50 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:50 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:50 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:50 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'stop' for song: Bad (at 10930 ms) Dec 21 10:26:51 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:51 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac" Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 474ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 473ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand addid "http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac" took 1 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: MPD COMMAND [object Object] Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 4ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 3ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand play took 3 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 2ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 0ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 4 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus stop Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 23ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 42ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 42ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 41ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 48 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: 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":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 79ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 78ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Dec 21 10:26:51 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 55 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 55ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 55 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: [jellyfin-play]: Mark song Smooth Criminal as played by Jayne. Dec 21 10:26:51 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'start' for song: Smooth Criminal (at 0 ms) Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 7ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 6 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 7ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand status took 7 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 90ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 36ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Pushing Favourites {"favourite":false} Dec 21 10:26:51 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 35 milliseconds Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":258,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"559 Kbps","isStreaming":false,"title":"Smooth Criminal","artist":"Michael Jackson","album":"Bad [2001 Special Edition]","uri":"http://192.168.1.157:8096/Audio/f9b8835607f7665f9f8c5caf9453f96f/stream.flac?static=true&mediaSourceId=f9b8835607f7665f9f8c5caf9453f96f&tag=ef943f7d078ab1553700d69b78802121&t.flac","trackType":"flac"} Dec 21 10:26:51 jayne-volumio volumio[11547]: verbose: CURRENT POSITION 3 Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState stateService play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::syncState currentStatus play Dec 21 10:26:51 jayne-volumio volumio[11547]: info: Received an update from plugin. extracting info from payload Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:26:51 jayne-volumio volumio[11547]: info: ------------------------------ 59ms Dec 21 10:26:51 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'timeupdate' for song: Smooth Criminal (at 0 ms) Dec 21 10:26:51 jayne-volumio volumio[11547]: info: [jellyfin-play]: Reported 'timeupdate' for song: Smooth Criminal (at 848 ms) Dec 21 10:27:30 jayne-volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::ClearQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::stPlaybackTimer Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::serviceStop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 3 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::serviceStop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::stop Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::clearPlayQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=86d9514b782a7a271db5acbe130ddaef Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=bb5d49ce012487e646a493bfd0114b65 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPlay Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::play index 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::addQueueItems Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::addQueueItems Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Preload queue cleared Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Adding Item to queue: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Using cached record of: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/library@parentId=805d021ee7097cd80c462d75e2f5354c/albums@parentId=805d021ee7097cd80c462d75e2f5354c@startIndex=47@noExplode=1/songs@albumId=7aeb46bccb534316f935efe79d8be1a1/song@songId=f9b8835607f7665f9f8c5caf9453f96f Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::stop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::saveQueue Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::play index undefined Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::updateTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrackBlock Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::startPlaybackTimer Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jayne@67da3d2dad0544fd9397f27ae71295f7/song@songId=7253091e65be35b7fa9466ca1c580915 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: [jellyfin-play] Stream URL for Dirty Diana: http://192.168.1.157:8096/Audio/7253091e65be35b7fa9466ca1c580915/stream.flac?static=true&mediaSourceId=7253091e65be35b7fa9466ca1c580915&tag=5957e6771431e6ba4da85f1003faf045 Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand stop Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 40 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand stop took 2 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand clear Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces state update: player Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::getState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand status Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand status took 3 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand clear took 2 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand status took 2 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.157:8096/Audio/7253091e65be35b7fa9466ca1c580915/stream.flac?static=true&mediaSourceId=7253091e65be35b7fa9466ca1c580915&tag=5957e6771431e6ba4da85f1003faf045&t.flac" Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ControllerMpd::pushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::servicePushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreStateMachine::pushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CoreCommandRouter::volumioPushState Dec 21 10:27:34 jayne-volumio volumio[11547]: info: CorePlayQueue::getTrack 2 Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ------------------------------ 18ms Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ------------------------------ 17ms Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Dec 21 10:27:34 jayne-volumio volumio[11547]: ---------------------------- MPD announces system playlist update Dec 21 10:27:34 jayne-volumio volumio[11547]: info: Ignoring MPD Status Update Dec 21 10:27:34 jayne-volumio volumio[11547]: error: updateQueue error: null Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ------------------------------ 18ms Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: info: sendMpdCommand playlistinfo took 18 milliseconds Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:27:34 jayne-volumio volumio[11547]: verbose: ControllerMpd::parseTrackInfo Dec 21 10:27:34 jayne-volumio volumio[11547]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 21 10:27:34 jayne-volumio volumio[11547]: info: ------------------------------ 23ms Dec 21 10:27:34 jayne-volumio volumio[11547]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:27:34 jayne-volumio volumio[11547]: TypeError: Cannot read properties of undefined (reading 'split') Dec 21 10:27:34 jayne-volumio volumio[11547]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Dec 21 10:27:34 jayne-volumio volumio[11547]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 21 10:27:34 jayne-volumio volumio[11547]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 21 10:27:34 jayne-volumio volumio[11547]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 21 10:27:34 jayne-volumio sudo[13125]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-21 10:26' Dec 21 10:27:34 jayne-volumio sudo[13125]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"