Dec 18 08:43:38 volumio volumio[3153]: info: CALLMETHOD: user_interface now_playing configSaveLocalizationSettings [object Object] Dec 18 08:43:38 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveLocalizationSettings Dec 18 08:43:38 volumio volumio[3153]: error: Failed callmethod call: Error: Invalid coordinates Dec 18 08:44:09 volumio volumio[3153]: info: CALLMETHOD: user_interface now_playing configSaveMetadataServiceSettings [object Object] Dec 18 08:44:09 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: now_playing , configSaveMetadataServiceSettings Dec 18 08:44:40 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Dec 18 08:44:40 volumio volumio[3153]: info: [jellyfin-browse] browseUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6 Dec 18 08:44:40 volumio volumio[3153]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"00d9f8efdd3b4614b808171a7e4a84cd","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"985e2f20771b95bb1989b48e930aead6","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Dec 18 08:44:40 volumio volumio[3153]: verbose: [jellyfin] getItemsFromAPI(): https://media.wordandahalf.org/jellyfin/Items?userId=00d9f8efdd3b4614b808171a7e4a84cd&startIndex=0&recursive=true&sortOrder=Ascending&parentId=985e2f20771b95bb1989b48e930aead6&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Dec 18 08:44:48 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::ClearQueue Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::stPlaybackTimer Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 0 Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::serviceStop Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 0 Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::serviceStop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::stop Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::clearPlayQueue Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:48 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand stop took 16 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 0 Dec 18 08:44:48 volumio volumio[3153]: 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":"You Can Close Your Eyes","artist":"Jacob Collier","album":"The Light For Days","uri":"https://media.wordandahalf.org/jellyfin/Audio/3b2959da99ed1c14b08af042465a1caf/stream.mp3?static=true&mediaSourceId=3b2959da99ed1c14b08af042465a1caf&tag=cb6b2d7e6f9e251fc08f885450efa412&t.mp3","trackType":"mp3"} Dec 18 08:44:48 volumio volumio[3153]: verbose: CURRENT POSITION 0 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState stateService stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: No code Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 0 Dec 18 08:44:48 volumio volumio[3153]: 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":"You Can Close Your Eyes","artist":"Jacob Collier","album":"The Light For Days","uri":"https://media.wordandahalf.org/jellyfin/Audio/3b2959da99ed1c14b08af042465a1caf/stream.mp3?static=true&mediaSourceId=3b2959da99ed1c14b08af042465a1caf&tag=cb6b2d7e6f9e251fc08f885450efa412&t.mp3","trackType":"mp3"} Dec 18 08:44:48 volumio volumio[3153]: verbose: CURRENT POSITION 0 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState stateService stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: No code Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 0 Dec 18 08:44:48 volumio volumio[3153]: 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":"You Can Close Your Eyes","artist":"Jacob Collier","album":"The Light For Days","uri":"https://media.wordandahalf.org/jellyfin/Audio/3b2959da99ed1c14b08af042465a1caf/stream.mp3?static=true&mediaSourceId=3b2959da99ed1c14b08af042465a1caf&tag=cb6b2d7e6f9e251fc08f885450efa412&t.mp3","trackType":"mp3"} Dec 18 08:44:48 volumio volumio[3153]: verbose: CURRENT POSITION 0 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState stateService stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: No code Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:48 volumio volumio[3153]: info: ------------------------------ 14ms Dec 18 08:44:48 volumio volumio[3153]: info: ------------------------------ 14ms Dec 18 08:44:48 volumio volumio[3153]: info: ------------------------------ 15ms Dec 18 08:44:48 volumio volumio[3153]: info: Pushing Favourites {"favourite":false} Dec 18 08:44:48 volumio volumio[3153]: info: Pushing Favourites {"service":"mpd","uri":"jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/song@songId=3b2959da99ed1c14b08af042465a1caf","favourite":false} Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPlay Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::play index 7 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:48 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:48 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:48 volumio volumio[3153]: info: Exploding uri jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 in service jellyfin Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-browse] explodeUri: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::play index undefined Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::startPlaybackTimer Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-play]: Reported 'stop' for song: You Can Close Your Eyes (at 92454 ms) Dec 18 08:44:48 volumio volumio[3153]: info: [jellyfin-play] Stream URL for My Kink Is Karma: https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0 Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand stop took 0 milliseconds Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 08:44:48 volumio volumio[3153]: info: sendMpdCommand clear took 0 milliseconds Dec 18 08:44:48 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand load "https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3" Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:48 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:48 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:48 volumio volumio[3153]: info: Dec 18 08:44:48 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:48 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:48 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:48 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:48 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:49 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:49 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:49 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand addid "https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3" Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 414ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 414ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 414ms Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand addid "https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3" took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:49 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:49 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand play Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand play took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 3 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 2 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 2 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 2 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 4ms Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 14ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 14ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 13ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 12ms Dec 18 08:44:49 volumio volumio[3153]: info: Pushing Favourites {"favourite":false} Dec 18 08:44:49 volumio volumio[3153]: info: Pushing Favourites {"favourite":false} Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 15 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 27ms Dec 18 08:44:49 volumio volumio[3153]: info: [jellyfin-play]: Mark song My Kink Is Karma as played by 5dL29Gi3. Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:49 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: verbose: STATE SERVICE {"status":"play","position":0,"seek":980,"duration":223,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"My Kink Is Karma","artist":"Chappell Roan","album":"The Rise and Fall of a Midwest Princess","uri":"https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3","trackType":"mp3"} Dec 18 08:44:49 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState stateService play Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus play Dec 18 08:44:49 volumio volumio[3153]: info: Received an update from plugin. extracting info from payload Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 9ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 8ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 8ms Dec 18 08:44:49 volumio volumio[3153]: info: Pushing Favourites {"favourite":false} Dec 18 08:44:49 volumio volumio[3153]: info: [jellyfin-play]: Reported 'timeupdate' for song: My Kink Is Karma (at 0 ms) Dec 18 08:44:49 volumio volumio[3153]: info: [jellyfin-play]: Reported 'start' for song: My Kink Is Karma (at 0 ms) Dec 18 08:44:49 volumio volumio[3153]: info: [jellyfin-play]: Reported 'timeupdate' for song: My Kink Is Karma (at 980 ms) Dec 18 08:44:49 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::ClearQueue Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::stPlaybackTimer Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::serviceStop Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::serviceStop Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::stop Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::clearPlayQueue Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:49 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPlay Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::play index 8 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:49 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:49 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:49 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::play index undefined Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::startPlaybackTimer Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand stop took 12 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: Dec 18 08:44:49 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 18 08:44:49 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:49 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:49 volumio volumio[3153]: info: CorePlayQueue::getTrack 8 Dec 18 08:44:49 volumio volumio[3153]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 6ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 7ms Dec 18 08:44:49 volumio volumio[3153]: info: ------------------------------ 7ms Dec 18 08:44:49 volumio volumio[3153]: info: Pushing Favourites {"favourite":false} Dec 18 08:44:50 volumio volumio[3153]: info: [jellyfin-play] Stream URL for Picture You: https://media.wordandahalf.org/jellyfin/Audio/9cf87033548422f234a3066aa8b8edf3/stream.mp3?static=true&mediaSourceId=9cf87033548422f234a3066aa8b8edf3&tag=9a74cdcb55f58dae92629f2eaf52de84 Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand stop took 0 milliseconds Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand clear took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand load "https://media.wordandahalf.org/jellyfin/Audio/9cf87033548422f234a3066aa8b8edf3/stream.mp3?static=true&mediaSourceId=9cf87033548422f234a3066aa8b8edf3&tag=9a74cdcb55f58dae92629f2eaf52de84&t.mp3" Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: [jellyfin-play]: Reported 'stop' for song: My Kink Is Karma (at 1476 ms) Dec 18 08:44:50 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::ClearQueue Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::clearPlayQueue Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:50 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=ed3025ef01a101929395a26c26fa086e Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9b011a94dd325f3195774d1fc3ec9e56 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=c7f1fe093c15f780d8b28e74c52eb465 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=d42fbbcf118a10f3c3f97c507d0f9658 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=7636adfab5b66b8e39acafbb31aae6fe Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=5ca22f7f8ce2d140b3a97811ad0b79d2 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=fb5d01eee048cac4ade8b7786e63cadd Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPlay Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::play index 7 Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::addQueueItems Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::addQueueItems Dec 18 08:44:50 volumio volumio[3153]: info: Preload queue cleared Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=9cf87033548422f234a3066aa8b8edf3 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=014c12f50bcb5e158785bf2224ef2560 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=78d19cda069590599af09ae66e853073 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=e0785d1398bffd71c670f19e905a3717 Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=4f6152378375be0af1c7539e293e8bdb Dec 18 08:44:50 volumio volumio[3153]: info: Adding Item to queue: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:50 volumio volumio[3153]: info: Using cached record of: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/songs@albumId=c161c1c3ca13310775609cba41038700/songs@albumId=985e2f20771b95bb1989b48e930aead6/song@songId=54b074e74c88ab442b8d06f843330843 Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushQueue Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::saveQueue Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::play index undefined Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::updateTrackBlock Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrackBlock Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::startPlaybackTimer Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:50 volumio volumio[3153]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/5dL29Gi3@f3e6aced056c44ccbe3fafeeb382cb9d/song@songId=e69109c24b31927b81ee700176084423 Dec 18 08:44:50 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 259ms Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand addid "https://media.wordandahalf.org/jellyfin/Audio/9cf87033548422f234a3066aa8b8edf3/stream.mp3?static=true&mediaSourceId=9cf87033548422f234a3066aa8b8edf3&tag=9a74cdcb55f58dae92629f2eaf52de84&t.mp3" Dec 18 08:44:50 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:50 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 258ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 258ms Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand addid "https://media.wordandahalf.org/jellyfin/Audio/9cf87033548422f234a3066aa8b8edf3/stream.mp3?static=true&mediaSourceId=9cf87033548422f234a3066aa8b8edf3&tag=9a74cdcb55f58dae92629f2eaf52de84&t.mp3" took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:50 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:50 volumio volumio[3153]: verbose: MPD COMMAND [object Object] Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand play Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand play took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 2ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 1ms Dec 18 08:44:50 volumio volumio[3153]: info: [jellyfin-play] Stream URL for My Kink Is Karma: https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0 Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand stop Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand stop took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand clear Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces state update: player Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::getState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand status Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand clear took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::sendMpdCommand load "https://media.wordandahalf.org/jellyfin/Audio/e69109c24b31927b81ee700176084423/stream.mp3?static=true&mediaSourceId=e69109c24b31927b81ee700176084423&tag=c8781fe77ff5e4169fc14f07017facb0&t.mp3" Dec 18 08:44:50 volumio volumio[3153]: info: Dec 18 08:44:50 volumio volumio[3153]: ---------------------------- MPD announces system playlist update Dec 18 08:44:50 volumio volumio[3153]: info: Ignoring MPD Status Update Dec 18 08:44:50 volumio volumio[3153]: error: updateQueue error: null Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand status took 1 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand status took 0 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 0ms Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:50 volumio volumio[3153]: info: sendMpdCommand playlistinfo took 0 milliseconds Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseState Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:50 volumio volumio[3153]: verbose: ControllerMpd::parseTrackInfo Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:50 volumio volumio[3153]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 18 08:44:50 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::syncState stateService stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: info: No code Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: info: ControllerMpd::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::servicePushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: info: CorePlayQueue::getTrack 7 Dec 18 08:44:50 volumio volumio[3153]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 18 08:44:50 volumio volumio[3153]: verbose: CURRENT POSITION 7 Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::syncState stateService stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::syncState currentStatus stop Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: info: No code Dec 18 08:44:50 volumio volumio[3153]: info: CoreStateMachine::pushState Dec 18 08:44:50 volumio volumio[3153]: info: CoreCommandRouter::volumioPushState Dec 18 08:44:50 volumio volumio[3153]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 12ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 11ms Dec 18 08:44:50 volumio volumio[3153]: info: ------------------------------ 12ms Dec 18 08:44:50 volumio volumio[3153]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 08:44:50 volumio volumio[3153]: TypeError: Cannot read properties of undefined (reading 'split') Dec 18 08:44:50 volumio volumio[3153]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Dec 18 08:44:50 volumio volumio[3153]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 18 08:44:50 volumio volumio[3153]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 18 08:44:50 volumio volumio[3153]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 08:44:50 volumio sudo[7371]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 08:43' Dec 18 08:44:50 volumio sudo[7371]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"