Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:37:31 raspi2 volumio[8669]: error: error Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::ClearQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::stPlaybackTimer Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::serviceStop Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::serviceStop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::stop Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::clearPlayQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:21 raspi2 volumio[8669]: info: Mar 04 11:38:21 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand stop took 85 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:21 raspi2 volumio[8669]: info: Mar 04 11:38:21 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:21 raspi2 volumio[8669]: info: Mar 04 11:38:21 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand status took 13 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand status took 11 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand status took 9 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand status took 6 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 6 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 5 milliseconds Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:21 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:21 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: No code Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:21 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: No code Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:21 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: No code Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:21 raspi2 volumio[8669]: info: ------------------------------ 230ms Mar 04 11:38:21 raspi2 volumio[8669]: info: ------------------------------ 228ms Mar 04 11:38:21 raspi2 volumio[8669]: info: ------------------------------ 227ms Mar 04 11:38:21 raspi2 volumio[8669]: info: Pushing Favourites {"favourite":false} Mar 04 11:38:21 raspi2 volumio[8669]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/song@songId=f34b88ef9ab45c9af809dba51663ee57","favourite":false} Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::ClearQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::clearPlayQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPlay Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::play index 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::play index undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-play]: Reported 'stop' for song: Dosis (at 151344 ms) Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPlay Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::play index 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:21 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:21 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:21 raspi2 volumio[8669]: info: Exploding uri jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 in service jellyfin Mar 04 11:38:21 raspi2 volumio[8669]: info: [jellyfin-browse] explodeUri: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::play index undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:21 raspi2 volumio[8669]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:38:21 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:22 raspi2 volumio[8669]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:22 raspi2 volumio[8669]: info: [jellyfin-play] Stream URL for Dosis: http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377 Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand stop took 49 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand clear took 32 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 137ms Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 134ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 131ms Mar 04 11:38:22 raspi2 volumio[8669]: info: [jellyfin-play] Stream URL for Dosis: http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377 Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand addid "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" took 38 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand stop took 7 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 46ms Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand clear took 42 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 35ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 33ms Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand play Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 120ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 119ms Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand play took 116 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 115ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 111ms Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand addid "http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac" took 13 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 21ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 8ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 6ms Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand play Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 20ms Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand play took 17 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 16ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 15ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 43 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 43 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 17 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: info: [jellyfin-play]: Mark song Dosis as played by admin. Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 44 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 41 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 37 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 33 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 32 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 23 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand status took 19 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:22 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:22 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus stop Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:22 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:22 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:22 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 154ms Mar 04 11:38:22 raspi2 volumio[8669]: info: ------------------------------ 178ms Mar 04 11:38:22 raspi2 volumio[8669]: info: Pushing Favourites {"favourite":false} Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:22 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: info: Mar 04 11:38:22 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 158 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 157 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 156 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 154 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 154 milliseconds Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:22 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:22 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:22 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:22 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:22 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:23 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:23 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:23 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:23 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:23 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:23 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:23 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:23 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:23 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 769ms Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 769ms Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 751ms Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 750ms Mar 04 11:38:23 raspi2 volumio[8669]: info: Pushing Favourites {"favourite":false} Mar 04 11:38:23 raspi2 volumio[8669]: info: Mar 04 11:38:23 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:23 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:23 raspi2 volumio[8669]: info: Mar 04 11:38:23 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:23 raspi2 volumio[8669]: info: Mar 04 11:38:23 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:23 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:23 raspi2 volumio[8669]: info: Mar 04 11:38:23 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand status took 922 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 927ms Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand status took 914 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 48ms Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand status took 42 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: ------------------------------ 47ms Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand status took 37 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:23 raspi2 volumio[8669]: info: [jellyfin-play]: Reported 'start' for song: Dosis (at 0 ms) Mar 04 11:38:23 raspi2 volumio[8669]: info: [jellyfin-play]: Reported 'timeupdate' for song: Dosis (at 0 ms) Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 84 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 94 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 94 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 95 milliseconds Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:23 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:23 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:23 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"571 Kbps","isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:23 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:23 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:23 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:24 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1786,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:24 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:24 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:24 raspi2 volumio[8669]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1786,"duration":191,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"759 Kbps","isStreaming":false,"title":"Dosis","artist":"La Castañeda","album":"XXX Años De Locura","uri":"http://100.93.223.101:8096/Audio/d1eb69f9f7fe4d7c54492225c32a3147/stream.flac?static=true&mediaSourceId=d1eb69f9f7fe4d7c54492225c32a3147&tag=cf61ab1a2eb3c0cf5630bcce549ca377&t.flac","trackType":"flac"} Mar 04 11:38:24 raspi2 volumio[8669]: verbose: CURRENT POSITION 1 Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::syncState stateService play Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::syncState currentStatus play Mar 04 11:38:24 raspi2 volumio[8669]: info: Received an update from plugin. extracting info from payload Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:24 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:24 raspi2 volumio[8669]: info: ------------------------------ 1411ms Mar 04 11:38:24 raspi2 volumio[8669]: info: ------------------------------ 535ms Mar 04 11:38:24 raspi2 volumio[8669]: info: ------------------------------ 520ms Mar 04 11:38:24 raspi2 volumio[8669]: info: Pushing Favourites {"favourite":false} Mar 04 11:38:24 raspi2 volumio[8669]: info: [jellyfin-play]: Mark song Dosis as played by admin. Mar 04 11:38:24 raspi2 volumio[8669]: info: [jellyfin-play]: Reported 'timeupdate' for song: Dosis (at 881 ms) Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:30 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:31 raspi2 volumio[8669]: error: error Mar 04 11:38:39 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::ClearQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::stPlaybackTimer Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::serviceStop Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 1 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::serviceStop Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::stop Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::clearPlayQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:39 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPlay Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::play index 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::play index undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:39 raspi2 volumio[8669]: info: Mar 04 11:38:39 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand stop took 85 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:39 raspi2 volumio[8669]: info: Mar 04 11:38:39 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:39 raspi2 volumio[8669]: info: Mar 04 11:38:39 raspi2 volumio[8669]: ---------------------------- MPD announces state update: player Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::getState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand status Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand status took 30 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand status took 28 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand status took 27 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand status took 26 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseState Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 11 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 12 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:39 raspi2 volumio[8669]: verbose: ControllerMpd::parseTrackInfo Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 11:38:39 raspi2 volumio[8669]: info: ControllerMpd::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::servicePushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::pushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushState Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Mar 04 11:38:39 raspi2 volumio[8669]: info: ------------------------------ 117ms Mar 04 11:38:39 raspi2 volumio[8669]: info: ------------------------------ 114ms Mar 04 11:38:39 raspi2 volumio[8669]: info: ------------------------------ 112ms Mar 04 11:38:39 raspi2 volumio[8669]: info: Pushing Favourites {"favourite":false} Mar 04 11:38:39 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioReplaceandPlayItems Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::ClearQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::clearPlayQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::addQueueItems Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::addQueueItems Mar 04 11:38:39 raspi2 volumio[8669]: info: Preload queue cleared Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=f34b88ef9ab45c9af809dba51663ee57 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d1eb69f9f7fe4d7c54492225c32a3147 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=c2ade54b4ec2ccf147e43175b284448b Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=ba24634d2c4292381b702f133d2e637f Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=b5a40b49603e70a0ab048f981999f686 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=0f8ee76a265254890bd8460dfd782c13 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=3d8aceb89086bdc60dbf275724ecb213 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=d2418a5f9b72e665fbda312cb80b8ed7 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=1c4b46224145709eb623f3844769c994 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=e30104cacc7ae3e073e9a04d96722c35 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=839ce610a72cdc288f4ff4b12abe0136 Mar 04 11:38:39 raspi2 volumio[8669]: info: Adding Item to queue: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:39 raspi2 volumio[8669]: info: Using cached record of: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/songs@albumId=5a2e234fabcdc1d65f89195d88b979ce/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPushQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::saveQueue Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::updateTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrackBlock Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreCommandRouter::volumioPlay Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::play index 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::stop Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::play index undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: CoreStateMachine::startPlaybackTimer Mar 04 11:38:39 raspi2 volumio[8669]: info: CorePlayQueue::getTrack 11 Mar 04 11:38:39 raspi2 volumio[8669]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/admin@84ed3b41aa974799902e0fb151c2db45/song@songId=aa6af501ac185eef06f2b026b722e6c1 Mar 04 11:38:40 raspi2 volumio[8669]: info: [jellyfin-play]: Reported 'stop' for song: Dosis (at 16368 ms) Mar 04 11:38:40 raspi2 volumio[8669]: info: [jellyfin-play] Stream URL for Veneno: http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:40 raspi2 volumio[8669]: info: sendMpdCommand stop took 2 milliseconds Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: info: sendMpdCommand clear took 10 milliseconds Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b&t.flac" Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:40 raspi2 volumio[8669]: info: ------------------------------ 10ms Mar 04 11:38:40 raspi2 volumio[8669]: info: [jellyfin-play] Stream URL for Veneno: http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand stop Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b&t.flac" Mar 04 11:38:40 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:40 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:40 raspi2 volumio[8669]: info: ------------------------------ 104ms Mar 04 11:38:40 raspi2 volumio[8669]: info: ------------------------------ 104ms Mar 04 11:38:40 raspi2 volumio[8669]: info: sendMpdCommand stop took 85 milliseconds Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand clear Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: info: sendMpdCommand addid "http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b&t.flac" took 23 milliseconds Mar 04 11:38:40 raspi2 volumio[8669]: info: sendMpdCommand clear took 10 milliseconds Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand load "http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b&t.flac" Mar 04 11:38:40 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:40 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:40 raspi2 volumio[8669]: verbose: MPD COMMAND [object Object] Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: info: Mar 04 11:38:40 raspi2 volumio[8669]: ---------------------------- MPD announces system playlist update Mar 04 11:38:40 raspi2 volumio[8669]: info: Ignoring MPD Status Update Mar 04 11:38:40 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:40 raspi2 volumio[8669]: error: updateQueue error: null Mar 04 11:38:40 raspi2 volumio[8669]: info: ------------------------------ 29ms Mar 04 11:38:40 raspi2 volumio[8669]: info: ------------------------------ 23ms Mar 04 11:38:40 raspi2 volumio[8669]: verbose: ControllerMpd::sendMpdCommand addid "http://100.93.223.101:8096/Audio/aa6af501ac185eef06f2b026b722e6c1/stream.flac?static=true&mediaSourceId=aa6af501ac185eef06f2b026b722e6c1&tag=cbe5917222c178273e1e38ebb856921b&t.flac" Mar 04 11:38:40 raspi2 volumio[8669]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:38:40 raspi2 volumio[8669]: Error: [50@0] {addtagid} No such song Mar 04 11:38:40 raspi2 volumio[8669]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Mar 04 11:38:40 raspi2 volumio[8669]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Mar 04 11:38:40 raspi2 volumio[8669]: at Socket.emit (node:events:514:28) Mar 04 11:38:40 raspi2 volumio[8669]: at addChunk (node:internal/streams/readable:343:12) Mar 04 11:38:40 raspi2 volumio[8669]: at readableAddChunk (node:internal/streams/readable:312:11) Mar 04 11:38:40 raspi2 volumio[8669]: at Readable.push (node:internal/streams/readable:253:10) Mar 04 11:38:40 raspi2 volumio[8669]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Mar 04 11:38:40 raspi2 volumio[8669]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 04 11:38:41 raspi2 sudo[10034]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-04 11:37' Mar 04 11:38:41 raspi2 sudo[10034]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"