Feb 17 14:49:04 volumio volumio[1137]: info: CoreCommandRouter::volumioGetQueue Feb 17 14:49:04 volumio volumio[1137]: info: CoreStateMachine::getQueue Feb 17 14:49:04 volumio volumio[1137]: info: CorePlayQueue::getQueue Feb 17 14:49:05 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 17 14:49:06 volumio volumio[1137]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 17 14:49:06 volumio volumio[1137]: info: Received Get System Version Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 17 14:49:06 volumio volumio[1137]: info: Received Get System Info Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 17 14:49:06 volumio volumio[1137]: info: Discovery: Getting this device information Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:06 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 17 14:49:10 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 14:49:10 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:49:12 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 17 14:49:13 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:49:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::ClearQueue Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::serviceStop Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::serviceStop Feb 17 14:49:14 volumio volumio[1137]: info: Airplay Stop Feb 17 14:49:14 volumio volumio[1137]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::clearPlayQueue Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Feb 17 14:49:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:49:14 volumio volumio[1137]: info: Adding Item to queue: https://rozhlas.stream/ddur_mp3_256.mp3 Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Feb 17 14:49:14 volumio volumio[1137]: verbose: UNSET VOLATILE: Service: airplay_emulation Feb 17 14:49:14 volumio volumio[1137]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::play index 0 Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::stPlaybackTimer Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::serviceStop Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:14 volumio volumio[1137]: info: CoreCommandRouter::serviceStop Feb 17 14:49:14 volumio volumio[1137]: info: [1771336154671] ControllerWebradio::stop Feb 17 14:49:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:49:14 volumio volumio[1137]: info: sendMpdCommand stop took 12 milliseconds Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:14 volumio sudo[28467]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1603 Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:14 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:49:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:14 volumio volumio[1137]: info: [1771336154685] ControllerWebradio::clearAddPlayTrack Feb 17 14:49:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:49:14 volumio sudo[28467]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 14:49:14 volumio volumio[1137]: info: sendMpdCommand stop took 5 milliseconds Feb 17 14:49:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:49:14 volumio volumio[1137]: info: Feb 17 14:49:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:14 volumio volumio[1137]: info: sendMpdCommand clear took 3 milliseconds Feb 17 14:49:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:14 volumio sudo[28467]: pam_unix(sudo:session): session closed for user root Feb 17 14:49:14 volumio shairport-sync[28302]: {"time":1771336103118,"response":"startAirplayPlayback Success"} Feb 17 14:49:14 volumio volumio[1137]: info: Feb 17 14:49:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Feb 17 14:49:14 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Feb 17 14:49:14 volumio systemd[1]: shairport-sync.service: Consumed 38.190s CPU time. Feb 17 14:49:14 volumio volumio[1137]: info: Feb 17 14:49:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:14 volumio volumio[1137]: info: ------------------------------ 22ms Feb 17 14:49:14 volumio volumio[1137]: info: Shairport-Sync paused with USR2 Feb 17 14:49:14 volumio sudo[28470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1603 Feb 17 14:49:14 volumio sudo[28470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 14:49:14 volumio sudo[28470]: pam_unix(sudo:session): session closed for user root Feb 17 14:49:14 volumio volumio[1137]: info: Cannot execute Shairport-sync USR2 kill: Error: Command failed: /usr/bin/sudo /bin/kill -USR2 $(pidof shairport-sync) Feb 17 14:49:14 volumio volumio[1137]: /bin/kill: (1603): No such process Feb 17 14:49:15 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:15 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:15 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:15 volumio volumio[1137]: info: ------------------------------ 884ms Feb 17 14:49:15 volumio volumio[1137]: info: ------------------------------ 875ms Feb 17 14:49:15 volumio volumio[1137]: info: sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" took 2 milliseconds Feb 17 14:49:15 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:49:15 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Feb 17 14:49:15 volumio volumio[1137]: info: Feb 17 14:49:15 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:15 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:15 volumio volumio[1137]: info: Feb 17 14:49:15 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:15 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:15 volumio volumio[1137]: info: Feb 17 14:49:15 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:15 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:15 volumio volumio[1137]: info: sendMpdCommand play took 5 milliseconds Feb 17 14:49:15 volumio volumio[1137]: info: ------------------------------ 5ms Feb 17 14:49:15 volumio volumio[1137]: info: ------------------------------ 3ms Feb 17 14:49:15 volumio volumio[1137]: info: ------------------------------ 2ms Feb 17 14:49:15 volumio volumio[1137]: info: Restarting Shairport-Sync after stop Feb 17 14:49:15 volumio sudo[28488]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 17 14:49:15 volumio sudo[28488]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 14:49:15 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 17 14:49:15 volumio sudo[28488]: pam_unix(sudo:session): session closed for user root Feb 17 14:49:15 volumio volumio[1137]: info: Shairport-Sync retarted Feb 17 14:49:15 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:15 volumio volumio[1137]: info: Listing playlists Feb 17 14:49:15 volumio volumio[1137]: info: Listing playlists Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 14 milliseconds Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 19 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 18 milliseconds Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 16 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 14 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 12 milliseconds Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"11.025 kHz","bitdepth":"16 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"ddur_mp3_256.mp3","artist":null,"album":null,"uri":"https://rozhlas.stream/ddur_mp3_256.mp3","trackType":"mp3"} Feb 17 14:49:16 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::syncState stateService play Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 14:49:16 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::syncState stateService stop Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus play Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 55ms Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: [1771336156535] ControllerWebradio::clearAddPlayTrack Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 61ms Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 59ms Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 79ms Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 79ms Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 58ms Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 66 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand stop took 21 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 3 milliseconds Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 81ms Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 18ms Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:16 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand clear took 29 milliseconds Feb 17 14:49:16 volumio volumio[1137]: info: sendMpdCommand status took 14 milliseconds Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:16 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:16 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:16 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:16 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:16 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 23ms Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:16 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:16 volumio volumio[1137]: info: Feb 17 14:49:16 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:16 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:16 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:16 volumio volumio[1137]: info: ------------------------------ 15ms Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:17 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:17 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 840ms Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 838ms Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:17 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" took 3 milliseconds Feb 17 14:49:17 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:17 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:17 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand play took 5 milliseconds Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 2ms Feb 17 14:49:17 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Feb 17 14:49:17 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:49:17 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:17 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:17 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:49:17 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:17 volumio volumio[1137]: info: [1771336157790] ControllerWebradio::clearAddPlayTrack Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand stop took 132 milliseconds Feb 17 14:49:17 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:17 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand status took 2 milliseconds Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand clear took 1 milliseconds Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:17 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:17 volumio volumio[1137]: info: Feb 17 14:49:17 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:17 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:17 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 5ms Feb 17 14:49:17 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 17 14:49:17 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:17 volumio volumio[1137]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 17 14:49:17 volumio volumio[1137]: info: ------------------------------ 8ms Feb 17 14:49:18 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:18 volumio volumio[1137]: info: Feb 17 14:49:18 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:18 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:18 volumio volumio[1137]: info: sendMpdCommand status took 905 milliseconds Feb 17 14:49:18 volumio volumio[1137]: info: sendMpdCommand status took 904 milliseconds Feb 17 14:49:18 volumio volumio[1137]: info: sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" took 2 milliseconds Feb 17 14:49:18 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:18 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:49:18 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Feb 17 14:49:18 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:18 volumio volumio[1137]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 14:49:18 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::syncState stateService stop Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: No code Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:18 volumio volumio[1137]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 14:49:18 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::syncState stateService stop Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: No code Feb 17 14:49:18 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:18 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 946ms Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 945ms Feb 17 14:49:18 volumio volumio[1137]: info: Feb 17 14:49:18 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:18 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:18 volumio volumio[1137]: info: Feb 17 14:49:18 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:18 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 46ms Feb 17 14:49:18 volumio volumio[1137]: info: sendMpdCommand play took 44 milliseconds Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 3ms Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 3ms Feb 17 14:49:18 volumio volumio[1137]: info: Feb 17 14:49:18 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:18 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:18 volumio volumio[1137]: info: Feb 17 14:49:18 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:18 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 12ms Feb 17 14:49:18 volumio volumio[1137]: info: ------------------------------ 11ms Feb 17 14:49:19 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Feb 17 14:49:19 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:49:19 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:19 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:19 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:49:19 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:19 volumio volumio[1137]: info: [1771336159583] ControllerWebradio::clearAddPlayTrack Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:49:19 volumio volumio[1137]: info: Feb 17 14:49:19 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:19 volumio volumio[1137]: info: sendMpdCommand stop took 13 milliseconds Feb 17 14:49:19 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:49:19 volumio volumio[1137]: info: Feb 17 14:49:19 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:19 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:19 volumio volumio[1137]: info: sendMpdCommand status took 1 milliseconds Feb 17 14:49:19 volumio volumio[1137]: info: sendMpdCommand clear took 2 milliseconds Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:19 volumio volumio[1137]: info: Feb 17 14:49:19 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:19 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:19 volumio volumio[1137]: info: Feb 17 14:49:19 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:19 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:19 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:49:19 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:49:19 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 4 milliseconds Feb 17 14:49:19 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:19 volumio volumio[1137]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 17 14:49:19 volumio volumio[1137]: info: ------------------------------ 8ms Feb 17 14:49:20 volumio volumio[1137]: info: Airplay started streaming, receiving metadatas Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioStop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: Airplay started streaming, receiving metadatas Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioStop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: Airplay started streaming, receiving metadatas Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioStop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio shairport-sync[28529]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 17 14:49:20 volumio shairport-sync[28529]: Dload Upload Total Spent Left Speed Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received airplay_emulation Feb 17 14:49:20 volumio shairport-sync[28529]: [79B blob data] Feb 17 14:49:20 volumio shairport-sync[28530]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 17 14:49:20 volumio shairport-sync[28530]: Dload Upload Total Spent Left Speed Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- Client requests AirplayActive Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , setAirplayActive Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- Client requests Start Airplay PlaybackRoutine Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Feb 17 14:49:20 volumio volumio[1137]: info: Airplay playback start Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio shairport-sync[28530]: [79B blob data] Feb 17 14:49:20 volumio shairport-sync[28529]: [79B blob data] Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioStop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:49:20 volumio shairport-sync[28529]: {"time":1771336160076,"response":"airplayActive Success"} Feb 17 14:49:20 volumio shairport-sync[28530]: [79B blob data] Feb 17 14:49:20 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" Feb 17 14:49:20 volumio volumio[1137]: info: sendMpdCommand status took 899 milliseconds Feb 17 14:49:20 volumio volumio[1137]: info: sendMpdCommand status took 898 milliseconds Feb 17 14:49:20 volumio volumio[1137]: info: sendMpdCommand add "https://rozhlas.stream/ddur_mp3_256.mp3" took 1 milliseconds Feb 17 14:49:20 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:20 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:49:20 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Feb 17 14:49:20 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 14:49:20 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::syncState stateService stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: No code Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:49:20 volumio volumio[1137]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 17 14:49:20 volumio volumio[1137]: verbose: CURRENT POSITION 0 Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::syncState stateService stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::syncState currentStatus stop Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: No code Feb 17 14:49:20 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:20 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 927ms Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 927ms Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:20 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:20 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:20 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:20 volumio volumio[1137]: info: sendMpdCommand play took 31 milliseconds Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 5ms Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:20 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:20 volumio volumio[1137]: info: Feb 17 14:49:20 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:49:20 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 18ms Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 17ms Feb 17 14:49:20 volumio volumio[1137]: info: ------------------------------ 15ms Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 10 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: info: Feb 17 14:49:21 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:49:21 volumio volumio[1137]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 17 milliseconds Feb 17 14:49:21 volumio volumio[1137]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": Device or resource busy Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 16 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand clearerror took 11 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 11 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 8 milliseconds Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 35ms Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand status took 29 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand clearerror took 24 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand clearerror took 24 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 20 milliseconds Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 71ms Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 69ms Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 70ms Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 62ms Feb 17 14:49:21 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 17 14:49:21 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:49:21 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:49:21 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:49:21 volumio volumio[1137]: info: ------------------------------ 71ms Feb 17 14:49:25 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:34 volumio kernel: mmc1: Controller never released inhibit bit(s). Feb 17 14:49:34 volumio kernel: brcmfmac: brcmf_sdio_readframes: read 1488 bytes from channel 2 failed: -5 Feb 17 14:49:34 volumio kernel: brcmfmac: brcmf_sdio_rxfail: abort command, terminate frame Feb 17 14:49:35 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:35 volumio volumio[1137]: info: Listing playlists Feb 17 14:49:35 volumio volumio[1137]: info: Listing playlists Feb 17 14:49:45 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:54 volumio kernel: mmc1: Controller never released inhibit bit(s). Feb 17 14:49:54 volumio kernel: brcmfmac: brcmf_sdio_readframes: read 1488 bytes from channel 2 failed: -5 Feb 17 14:49:54 volumio kernel: brcmfmac: brcmf_sdio_rxfail: abort command, terminate frame Feb 17 14:49:55 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:49:55 volumio volumio[1137]: info: Listing playlists Feb 17 14:49:55 volumio volumio[1137]: info: Listing playlists Feb 17 14:50:03 volumio nmbd[1002]: [2026/02/17 14:50:03.720193, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 17 14:50:03 volumio nmbd[1002]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.0.109 for name WORKGROUP<1d>. Feb 17 14:50:03 volumio nmbd[1002]: This response was from IP 192.168.0.106, reporting an IP address of 192.168.0.106. Feb 17 14:50:04 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 17 14:50:05 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 17 14:50:05 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 17 14:50:05 volumio systemd[1]: setdatetime-helper.service: Consumed 1.048s CPU time. Feb 17 14:50:05 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:50:06 volumio volumio[1137]: info: CoreCommandRouter::volumioGetState Feb 17 14:50:11 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: last_100 , handleBrowseUri Feb 17 14:50:11 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:11 volumio volumio[1137]: info: Preloading song: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Using previously assigned device Id for jellyfin@jellyfin: 5a7eaf56-2ef2-4b61-8afa-2b00df1e1cd3 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Returning existing auth promise Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Returning existing auth promise Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Returning existing auth promise Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Returning existing auth promise Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-conn] Login successful: jellyfin@jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f in service jellyfin Feb 17 14:50:11 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:11 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e in service mpd Feb 17 14:50:12 volumio volumio[1137]: error: scanFolder - failure to stat '/mnt/jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e' Feb 17 14:50:12 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc in service jellyfin Feb 17 14:50:12 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:12 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:13 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc in service jellyfin Feb 17 14:50:13 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:13 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::ClearQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::serviceStop Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::serviceStop Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::stop Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::clearPlayQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Feb 17 14:50:14 volumio volumio[1137]: verbose: UNSET VOLATILE: Service: mpd Feb 17 14:50:14 volumio volumio[1137]: info: Stopping Airplay Playback and sending pause command to client via USR2 Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::play index 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::stPlaybackTimer Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::serviceStop Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 0 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::serviceStop Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::stop Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:50:14 volumio sudo[28705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 28490 Feb 17 14:50:14 volumio sudo[28705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 17 14:50:14 volumio sudo[28705]: pam_unix(sudo:session): session closed for user root Feb 17 14:50:14 volumio shairport-sync[28530]: {"time":1771336160080,"response":"startAirplayPlayback Success"} Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand stop took 120 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand stop took 54 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=12/USR2 Feb 17 14:50:14 volumio systemd[1]: shairport-sync.service: Failed with result 'signal'. Feb 17 14:50:14 volumio systemd[1]: shairport-sync.service: Consumed 8.934s CPU time. Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 17 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 4 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 3 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: info: Shairport-Sync paused with USR2 Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 19 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 62ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 44ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 44ms Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play] Stream URL for When We Were Young: http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand stop took 1 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand clear took 2 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae&t.mp3" Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 10ms Feb 17 14:50:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:50:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae&t.mp3" Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 22ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 20ms Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand addid "http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae&t.mp3" took 4 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: MPD COMMAND [object Object] Feb 17 14:50:14 volumio volumio[1137]: verbose: MPD COMMAND [object Object] Feb 17 14:50:14 volumio volumio[1137]: verbose: MPD COMMAND [object Object] Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 9ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 4ms Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand play Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 6ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand play took 7 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 6ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 5ms Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::ClearQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::clearPlayQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be7bd4d52ee3593453fe0776d2eca6d2 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=e3acdfc18c1ce987fde7ff2783d30dba Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=f1683da515309b548d6f1dd823f27d15 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a017e3197d0ecc4751176031f781ceb9 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=bbf552e8d83ca01ea661c4f939de5d96 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6b1b68c960e4fdb21d8d04559da9e1a7 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b5b927436b30fd21f29bc7dc8bcccc5e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=87e8ddbd65929a76f63fb678bf38f0d5 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6cdbeabac2752af57cd3ceaed1a71f42 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=058b7d09e23dcad84eac30b9f1f0a533 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPlay Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::play index 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::addQueueItems Feb 17 14:50:14 volumio volumio[1137]: info: Preload queue cleared Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=80016a63fafb632a30628b3505b36d95 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ab11b1f84ad254d41f199612f9b4750f Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ec027459d4046f153c3dd4b91e742204 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=0580dd223400f0a9b0a76dd0a98d721a Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=ef579a6a5c22c1c1653ff114523b5fa4 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=10b7e00cc9c5dac3d6b630df9ac39ae0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6dedcf7d300c152999c410875744cc96 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33b514a16fa475a7c6053e45a73992a0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=33c7980ee94fa743f809c8354462f519 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2b0b61bbbd7f3da5618dd8d89c1a51c0 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=efb72d884650aee804a920cf88a4903b Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=8a67b88a8db8a128557934db4da47893 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=339c66403b8e8cd6ea04f473b05178fe Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4bcb32710294a9bf6852edab152b13d Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=dca7780781bd6c71cb6ffacba8d7fbb9 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=c58ef04727636a8167b12652445d98b4 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=b4a6df3c9734a344fccc3217627729a2 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=62278f1d78bca647f159ce733773edbc Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=437637de21e3348a6d1777d0a89f0b70 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=3a52fdfaff59989481d85d7d3945e803 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e Feb 17 14:50:14 volumio volumio[1137]: info: Using cached record of: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=fb50f88272ca969ba71c37701204675e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 Feb 17 14:50:14 volumio volumio[1137]: info: Adding Item to queue: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:14 volumio volumio[1137]: info: Exploding uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc in service jellyfin Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-browse] explodeUri: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::stop Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::play index undefined Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::startPlaybackTimer Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=578f9e4ab2f40507cae46015a33fdd6a Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 7 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 40 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 38 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 38 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 37 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 39ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 36 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 35 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 35ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 34 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 34ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 32 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 55ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 14 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 13 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 11 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 12 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 67ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 64ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 62ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 57ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 55ms Feb 17 14:50:14 volumio volumio[1137]: info: Pushing Favourites {"favourite":false} Feb 17 14:50:14 volumio volumio[1137]: info: Pushing Favourites {"favourite":false} Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play]: Mark song When We Were Young as played by jellyfin. Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=6efef2de37477331958f02e8e5a396dc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cffd4580f8a8d0a71dba72f6677c0a1e from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play]: Reported 'start' for song: When We Were Young (at 881 ms) Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=2c82bd479adc1e96c72c8fabca55b022 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a18e5df3eaf3693729655269d4547dcb from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=be5c562bb4ddc596470de77383c2310b from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=a1be72f928ea70484516dc601e922ded from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=cde7b181be6f31f67a40960162901130 from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: error: Commandrouter: Cannot explode uri jellyfin/jellyfin@8396adf5f93c4fa2bac4a731d17329de/song@songId=417adcb5c346653f7a0f0c7685bb6ddc from service jellyfin: AxiosError: Request failed with status code 404 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushQueue Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::saveQueue Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::updateTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrackBlock Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play]: Reported 'timeupdate' for song: When We Were Young (at 881 ms) Feb 17 14:50:14 volumio volumio[1137]: info: [jellyfin-play] Stream URL for When We Were Young: http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand stop Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand stop took 11 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand clear Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces state update: player Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::getState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand status Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: Feb 17 14:50:14 volumio volumio[1137]: ---------------------------- MPD announces system playlist update Feb 17 14:50:14 volumio volumio[1137]: info: Ignoring MPD Status Update Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 6 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 5 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand clear took 5 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 5 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand status took 4 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.0.217:8096/Audio/578f9e4ab2f40507cae46015a33fdd6a/stream.mp3?static=true&mediaSourceId=578f9e4ab2f40507cae46015a33fdd6a&tag=556950667074be6ed5b2d07080b4faae&t.mp3" Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseState Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ControllerMpd::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::servicePushState Feb 17 14:50:14 volumio volumio[1137]: info: CoreStateMachine::pushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: CoreCommandRouter::volumioPushState Feb 17 14:50:14 volumio volumio[1137]: info: CorePlayQueue::getTrack 10 Feb 17 14:50:14 volumio volumio[1137]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 20ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 19ms Feb 17 14:50:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:50:14 volumio volumio[1137]: error: updateQueue error: null Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 19ms Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 18ms Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 17 14:50:14 volumio volumio[1137]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: verbose: ControllerMpd::parseTrackInfo Feb 17 14:50:14 volumio volumio[1137]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 17 14:50:14 volumio volumio[1137]: info: ------------------------------ 25ms Feb 17 14:50:14 volumio volumio[1137]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 14:50:14 volumio volumio[1137]: TypeError: Cannot read properties of undefined (reading 'split') Feb 17 14:50:14 volumio volumio[1137]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Feb 17 14:50:14 volumio volumio[1137]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Feb 17 14:50:14 volumio volumio[1137]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Feb 17 14:50:14 volumio volumio[1137]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 17 14:50:15 volumio sudo[28740]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-17 14:49' Feb 17 14:50:15 volumio sudo[28740]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"