Dec 27 13:52:03 volumio sudo[2882]: pam_unix(sudo:session): session closed for user root Dec 27 13:52:03 volumio volumio[1439]: info: Cannot mount NAS Music at system boot, trial number 4 ,retrying in 5 seconds Dec 27 13:52:08 volumio sudo[3149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.1.135:personal_folder\\MyMusic /mnt/NAS/Music Dec 27 13:52:08 volumio sudo[3149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 13:52:12 volumio volumio[1439]: info: CoreCommandRouter::volumioGetQueue Dec 27 13:52:12 volumio volumio[1439]: info: CoreStateMachine::getQueue Dec 27 13:52:12 volumio volumio[1439]: info: CorePlayQueue::getQueue Dec 27 13:52:25 volumio volumio[1439]: info: CoreCommandRouter::volumioPrevious Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::previous Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock Dec 27 13:52:25 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::serviceClearAddPlay Dec 27 13:52:25 volumio volumio[1439]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 27 13:52:25 volumio volumio[1439]: error: WARNING: No clearAddPlayTracks method for service webradio Dec 27 13:52:25 volumio volumio[1439]: info: CoreCommandRouter::volumioPrevious Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::previous Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock Dec 27 13:52:25 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock Dec 27 13:52:25 volumio volumio[1439]: info: CoreStateMachine::serviceClearAddPlay Dec 27 13:52:25 volumio volumio[1439]: info: CoreCommandRouter::serviceClearAddPlayTracks Dec 27 13:52:25 volumio volumio[1439]: error: WARNING: No clearAddPlayTracks method for service webradio Dec 27 13:52:26 volumio nmbd[1213]: [2025/12/27 13:52:26.402856, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 27 13:52:26 volumio nmbd[1213]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.134 for name WORKGROUP<1d>. Dec 27 13:52:26 volumio nmbd[1213]: This response was from IP 192.168.1.158, reporting an IP address of 192.168.1.158. Dec 27 13:52:29 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay Dec 27 13:52:29 volumio volumio[1439]: info: CoreStateMachine::play index undefined Dec 27 13:52:29 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:29 volumio volumio[1439]: info: CorePlayQueue::getTrack 1 Dec 27 13:52:30 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay Dec 27 13:52:30 volumio volumio[1439]: info: CoreStateMachine::play index undefined Dec 27 13:52:30 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:30 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer Dec 27 13:52:30 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:30 volumio volumio[1439]: info: [1766843550770] ControllerWebradio::clearAddPlayTrack Dec 27 13:52:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 13:52:30 volumio volumio[1439]: info: sendMpdCommand stop took 14 milliseconds Dec 27 13:52:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 13:52:30 volumio volumio[1439]: info: sendMpdCommand clear took 1 milliseconds Dec 27 13:52:30 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:30 volumio volumio[1439]: info: Dec 27 13:52:30 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:30 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:30 volumio volumio[1439]: info: Dec 27 13:52:30 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:30 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:31 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay Dec 27 13:52:31 volumio volumio[1439]: info: CoreStateMachine::play index undefined Dec 27 13:52:31 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:31 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:31 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer Dec 27 13:52:31 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:31 volumio volumio[1439]: info: [1766843551591] ControllerWebradio::clearAddPlayTrack Dec 27 13:52:31 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 13:52:34 volumio mpd[1754]: exception: No such playlist Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 3584ms Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 3583ms Dec 27 13:52:34 volumio volumio[1439]: info: sendMpdCommand stop took 2802 milliseconds Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 5ms Dec 27 13:52:34 volumio volumio[1439]: info: sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" took 3 milliseconds Dec 27 13:52:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 13:52:34 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 13:52:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 8ms Dec 27 13:52:34 volumio volumio[1439]: info: sendMpdCommand clear took 6 milliseconds Dec 27 13:52:34 volumio volumio[1439]: info: sendMpdCommand play took 5 milliseconds Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 5ms Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 4ms Dec 27 13:52:34 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: info: Dec 27 13:52:34 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:34 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:34 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:34 volumio volumio[1439]: info: ------------------------------ 6ms Dec 27 13:52:36 volumio mpd[1754]: exception: No such playlist Dec 27 13:52:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:36 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:36 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:36 volumio volumio[1439]: info: Dec 27 13:52:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:36 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:36 volumio volumio[1439]: info: ------------------------------ 2074ms Dec 27 13:52:36 volumio volumio[1439]: info: ------------------------------ 2073ms Dec 27 13:52:36 volumio volumio[1439]: info: sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" took 2 milliseconds Dec 27 13:52:36 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 13:52:36 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play Dec 27 13:52:36 volumio volumio[1439]: info: Dec 27 13:52:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:36 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:36 volumio volumio[1439]: info: Dec 27 13:52:36 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:36 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:36 volumio volumio[1439]: info: ------------------------------ 6ms Dec 27 13:52:36 volumio volumio[1439]: info: sendMpdCommand play took 5 milliseconds Dec 27 13:52:36 volumio volumio[1439]: info: ------------------------------ 4ms Dec 27 13:52:36 volumio volumio[1439]: info: ------------------------------ 3ms Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio mpd[1754]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 27 13:52:38 volumio mpd[1754]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 27 13:52:38 volumio mpd[1754]: player: problems opening audio device while playing "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 16 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 26 milliseconds Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 26 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand clearerror took 13 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 13 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 11 milliseconds Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 58ms Dec 27 13:52:38 volumio volumio[1439]: info: Dec 27 13:52:38 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 50 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand clearerror took 44 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand clearerror took 43 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand status took 15 milliseconds Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 121ms Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 121ms Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 119ms Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 51 milliseconds Dec 27 13:52:38 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 49 milliseconds Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:38 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:38 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:38 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:38 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:38 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 135ms Dec 27 13:52:38 volumio volumio[1439]: info: ------------------------------ 99ms Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay Dec 27 13:52:42 volumio volumio[1439]: info: CoreStateMachine::play index undefined Dec 27 13:52:42 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: [1766843562900] ControllerWebradio::clearAddPlayTrack Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 13:52:42 volumio mpd[1754]: player: played "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand stop took 12 milliseconds Dec 27 13:52:42 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:42 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:42 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:42 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand status took 7 milliseconds Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand clear took 6 milliseconds Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand status took 3 milliseconds Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand status took 3 milliseconds Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:42 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:42 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 27 13:52:42 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:42 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:42 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:42 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 27 13:52:42 volumio volumio[1439]: info: ------------------------------ 33ms Dec 27 13:52:42 volumio volumio[1439]: info: ------------------------------ 32ms Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:42 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:42 volumio volumio[1439]: info: Dec 27 13:52:42 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:42 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:42 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:42 volumio volumio[1439]: info: ------------------------------ 35ms Dec 27 13:52:42 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 34 milliseconds Dec 27 13:52:42 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:42 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:52:42 volumio volumio[1439]: info: ------------------------------ 45ms Dec 27 13:52:45 volumio mpd[1754]: exception: No such playlist Dec 27 13:52:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:45 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:45 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:45 volumio volumio[1439]: info: ------------------------------ 2366ms Dec 27 13:52:45 volumio volumio[1439]: info: ------------------------------ 2365ms Dec 27 13:52:45 volumio volumio[1439]: info: Dec 27 13:52:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:45 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:45 volumio volumio[1439]: info: sendMpdCommand add "https://christmasradio.christmasradiogroup.com/" took 4 milliseconds Dec 27 13:52:45 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 27 13:52:45 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand play Dec 27 13:52:45 volumio volumio[1439]: info: Dec 27 13:52:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:45 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:45 volumio volumio[1439]: info: Dec 27 13:52:45 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:45 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:45 volumio volumio[1439]: info: ------------------------------ 7ms Dec 27 13:52:45 volumio volumio[1439]: info: sendMpdCommand play took 7 milliseconds Dec 27 13:52:45 volumio volumio[1439]: info: ------------------------------ 6ms Dec 27 13:52:45 volumio volumio[1439]: info: ------------------------------ 5ms Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio mpd[1754]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 27 13:52:47 volumio mpd[1754]: exception: Failed to open "default detected output" (sndio); Requested audio params cannot be satisfied Dec 27 13:52:47 volumio mpd[1754]: player: problems opening audio device while playing "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 9 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 17 milliseconds Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 18 milliseconds Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 17 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand clearerror took 13 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 12 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 55ms Dec 27 13:52:47 volumio volumio[1439]: info: Dec 27 13:52:47 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand clearerror took 59 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand clearerror took 58 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand clearerror took 57 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 45 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 44 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 42 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 41 milliseconds Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand status took 21 milliseconds Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 140ms Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 138ms Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 138ms Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 137ms Dec 27 13:52:47 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 27 13:52:47 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:47 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:47 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:47 volumio volumio[1439]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"","artist":null,"album":null,"uri":"https://christmasradio.christmasradiogroup.com/","trackType":"com/"} Dec 27 13:52:47 volumio volumio[1439]: verbose: CURRENT POSITION 0 Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState stateService pause Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::syncState currentStatus stop Dec 27 13:52:47 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:47 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:47 volumio volumio[1439]: info: ------------------------------ 100ms Dec 27 13:52:50 volumio volumio[1439]: info: CoreCommandRouter::volumioplayNextItems Dec 27 13:52:50 volumio volumio[1439]: info: CoreStateMachine::playNextItems Dec 27 13:52:50 volumio volumio[1439]: info: CorePlayQueue::addPlayNextItems Dec 27 13:52:50 volumio volumio[1439]: info: Preload queue cleared Dec 27 13:52:50 volumio volumio[1439]: info: Adding Item to play next: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb@nameStartsWith=H@saveFilter=%7B%22field%22%3A%22nameStartsWith%22%2C%22value%22%3A%22H%22%7D/songs@albumId=dab6fb28daf761b26ceddc15ba5eb50f Dec 27 13:52:50 volumio volumio[1439]: info: Exploding uri jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb@nameStartsWith=H@saveFilter=%7B%22field%22%3A%22nameStartsWith%22%2C%22value%22%3A%22H%22%7D/songs@albumId=dab6fb28daf761b26ceddc15ba5eb50f in service jellyfin Dec 27 13:52:50 volumio volumio[1439]: info: [jellyfin-browse] explodeUri: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb@nameStartsWith=H@saveFilter=%7B%22field%22%3A%22nameStartsWith%22%2C%22value%22%3A%22H%22%7D/songs@albumId=dab6fb28daf761b26ceddc15ba5eb50f Dec 27 13:52:50 volumio volumio[1439]: info: [jellyfin-conn] Using previously assigned device Id for davet@3aae36984ea0: 188c5556-e06e-44ae-9b69-d7ad1b20314f Dec 27 13:52:51 volumio volumio[1439]: info: [jellyfin-conn] Login successful: davet@3aae36984ea0 Dec 27 13:52:51 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue Dec 27 13:52:51 volumio volumio[1439]: info: CorePlayQueue::saveQueue Dec 27 13:52:51 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock Dec 27 13:52:51 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock Dec 27 13:52:52 volumio volumio[1439]: info: Preload queue cleared Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::ClearQueue Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::stop Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::clearPlayQueue Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::saveQueue Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::addQueueItems Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::addQueueItems Dec 27 13:52:52 volumio volumio[1439]: info: Preload queue cleared Dec 27 13:52:52 volumio volumio[1439]: info: Adding Item to queue: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb@nameStartsWith=H@saveFilter=%7B%22field%22%3A%22nameStartsWith%22%2C%22value%22%3A%22H%22%7D/songs@albumId=dab6fb28daf761b26ceddc15ba5eb50f Dec 27 13:52:52 volumio volumio[1439]: info: Using cached record of: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/library@parentId=7e64e319657a9516ec78490da03edccb/albums@parentId=7e64e319657a9516ec78490da03edccb@nameStartsWith=H@saveFilter=%7B%22field%22%3A%22nameStartsWith%22%2C%22value%22%3A%22H%22%7D/songs@albumId=dab6fb28daf761b26ceddc15ba5eb50f Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioPushQueue Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::saveQueue Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::updateTrackBlock Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrackBlock Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioPlay Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::play index 0 Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::stop Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::play index undefined Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::startPlaybackTimer Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/davet@51a53ae119ca40718b0c077fd4a5b846/song@songId=af3fbec5e58e4a17e2ef6929d0867f7e Dec 27 13:52:52 volumio volumio[1439]: info: [jellyfin-play] Stream URL for Cygnus X-1 Book II: http://192.168.1.135:34271/Audio/af3fbec5e58e4a17e2ef6929d0867f7e/stream.mp3?static=true&mediaSourceId=af3fbec5e58e4a17e2ef6929d0867f7e&tag=5e40feb57e8cccdc326ea10b073f69c6 Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand stop Dec 27 13:52:52 volumio mpd[1754]: player: played "https://christmasradio.christmasradiogroup.com/" Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand stop took 13 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand clear Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces state update: player Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::getState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand status Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:52 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand status took 5 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand status took 5 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand clear took 5 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand status took 5 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand status took 4 milliseconds Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.135:34271/Audio/af3fbec5e58e4a17e2ef6929d0867f7e/stream.mp3?static=true&mediaSourceId=af3fbec5e58e4a17e2ef6929d0867f7e&tag=5e40feb57e8cccdc326ea10b073f69c6&t.mp3" Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseState Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 27 13:52:52 volumio volumio[1439]: info: ControllerMpd::pushState Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::servicePushState Dec 27 13:52:52 volumio volumio[1439]: info: CoreStateMachine::pushState Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: CoreCommandRouter::volumioPushState Dec 27 13:52:52 volumio volumio[1439]: info: CorePlayQueue::getTrack 0 Dec 27 13:52:52 volumio volumio[1439]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current jellyfin Received mpd Dec 27 13:52:52 volumio volumio[1439]: info: ------------------------------ 26ms Dec 27 13:52:52 volumio volumio[1439]: info: ------------------------------ 27ms Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:52 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:52 volumio volumio[1439]: info: Dec 27 13:52:52 volumio volumio[1439]: ---------------------------- MPD announces system playlist update Dec 27 13:52:52 volumio volumio[1439]: info: Ignoring MPD Status Update Dec 27 13:52:52 volumio volumio[1439]: error: updateQueue error: null Dec 27 13:52:52 volumio volumio[1439]: info: ------------------------------ 28ms Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 27 13:52:52 volumio volumio[1439]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:52 volumio volumio[1439]: verbose: ControllerMpd::parseTrackInfo Dec 27 13:52:52 volumio volumio[1439]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:52:52 volumio volumio[1439]: info: ------------------------------ 35ms Dec 27 13:52:52 volumio volumio[1439]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:52:52 volumio volumio[1439]: TypeError: Cannot read properties of undefined (reading 'split') Dec 27 13:52:52 volumio volumio[1439]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:280:60) Dec 27 13:52:52 volumio volumio[1439]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 27 13:52:52 volumio volumio[1439]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Dec 27 13:52:52 volumio volumio[1439]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 13:52:52 volumio mpd[1754]: exception: No such playlist Dec 27 13:52:54 volumio sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 13:51' Dec 27 13:52:54 volumio sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"