-- Logs begin at Sat 2025-05-17 11:37:53 CST, end at Sat 2025-05-17 11:59:40 CST. -- May 17 11:58:01 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 17 11:58:01 volumio volumio[889]: info: TuneIn handleBrowseUri: tunein/local May 17 11:58:09 volumio nmbd[839]: [2025/05/17 11:58:09.764541, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 17 11:58:09 volumio nmbd[839]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.10.88 for name WORKGROUP<1d>. May 17 11:58:09 volumio nmbd[839]: This response was from IP 192.168.10.150, reporting an IP address of 192.168.10.150. May 17 11:58:11 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 17 11:58:11 volumio volumio[889]: info: TuneIn handleBrowseUri: tunein/best May 17 11:59:00 volumio volumio[889]: info: Clearing queue after UPNP request May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::ClearQueue May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::stop May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::stPlaybackTimer May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrackBlock May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::serviceStop May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::serviceStop May 17 11:59:00 volumio volumio[889]: info: [1747454340441] ControllerWebradio::stop May 17 11:59:00 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand stop May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::clearPlayQueue May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::saveQueue May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushQueue May 17 11:59:00 volumio volumio[889]: error: Upnp client error: Error: This socket has been ended by the other party May 17 11:59:00 volumio volumio[889]: info: May 17 11:59:00 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:00 volumio volumio[889]: info: sendMpdCommand stop took 16 milliseconds May 17 11:59:00 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:00 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:00 volumio volumio[889]: info: sendMpdCommand status took 1 milliseconds May 17 11:59:00 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:00 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:00 volumio volumio[889]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 11:59:00 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:00 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Darren Motamedy - Don't Cha Know ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} May 17 11:59:00 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:00 volumio volumio[889]: info: No code May 17 11:59:00 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:00 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:00 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:00 volumio volumio[889]: info: ------------------------------ 17ms May 17 11:59:02 volumio volumio[889]: info: Starting UPNP Playback May 17 11:59:02 volumio volumio[889]: info: Preparing playback through UPNP May 17 11:59:02 volumio volumio[889]: info: CoreCommandRouter::volumioGetState May 17 11:59:02 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:02 volumio volumio[889]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 1ms May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 0ms May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 1ms May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 2ms May 17 11:59:02 volumio volumio[889]: info: May 17 11:59:02 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:02 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 2ms May 17 11:59:02 volumio volumio[889]: info: ------------------------------ 2ms May 17 11:59:03 volumio volumio[889]: info: May 17 11:59:03 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:03 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:03 volumio volumio[889]: info: May 17 11:59:03 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:03 volumio volumio[889]: info: sendMpdCommand status took 1 milliseconds May 17 11:59:03 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:03 volumio volumio[889]: info: sendMpdCommand status took 1 milliseconds May 17 11:59:03 volumio volumio[889]: info: sendMpdCommand playlistinfo took 0 milliseconds May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:03 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:03 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:03 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Darren Motamedy - Don't Cha Know ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} May 17 11:59:03 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop May 17 11:59:03 volumio volumio[889]: info: ------------------------------ 5ms May 17 11:59:03 volumio volumio[889]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 11:59:03 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:03 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:03 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:03 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":1,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Darren Motamedy - Don't Cha Know ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} May 17 11:59:03 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus play May 17 11:59:03 volumio volumio[889]: info: Received an update from plugin. extracting info from payload May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:03 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:03 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:04 volumio volumio[889]: info: ------------------------------ 14ms May 17 11:59:04 volumio volumio[889]: info: May 17 11:59:04 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:04 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:04 volumio volumio[889]: info: May 17 11:59:04 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:04 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:04 volumio volumio[889]: info: May 17 11:59:04 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:04 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:04 volumio volumio[889]: info: May 17 11:59:04 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:04 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:04 volumio volumio[889]: info: ------------------------------ 4ms May 17 11:59:04 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds May 17 11:59:04 volumio volumio[889]: info: ------------------------------ 3ms May 17 11:59:04 volumio volumio[889]: info: sendMpdCommand status took 2 milliseconds May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:04 volumio volumio[889]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 11:59:04 volumio volumio[889]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:04 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:04 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:04 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:04 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":1,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Darren Motamedy - Don't Cha Know ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} May 17 11:59:04 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus play May 17 11:59:04 volumio volumio[889]: info: Received an update from plugin. extracting info from payload May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:04 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:04 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:04 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":1,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Darren Motamedy - Don't Cha Know ","artist":"SmoothJazz.com","album":null,"uri":"http://66.85.89.30:80/2585_128.mp3","trackType":"mp3"} May 17 11:59:04 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus play May 17 11:59:04 volumio volumio[889]: info: Received an update from plugin. extracting info from payload May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:04 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:04 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:04 volumio volumio[889]: info: ------------------------------ 25ms May 17 11:59:04 volumio volumio[889]: info: ------------------------------ 23ms May 17 11:59:06 volumio volumio[1469]: 38 May 17 11:59:06 volumio volumio[1469]: % Total % Received % Xferd Average Speed Time Time Time Current May 17 11:59:06 volumio volumio[1469]: Dload Upload Total Spent Left Speed May 17 11:59:06 volumio volumio[889]: info: May 17 11:59:06 volumio volumio[889]: ---------------------------- Client requests Volume 38 May 17 11:59:06 volumio volumio[889]: info: VolumeController::SetAlsaVolume38 May 17 11:59:06 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:06 volumio volumio[1469]: [158B blob data] May 17 11:59:06 volumio volumio[1469]: {"time":1747454346060,"response":"volume Success"}38 May 17 11:59:06 volumio volumio[1469]: 43 May 17 11:59:06 volumio volumio[1469]: % Total % Received % Xferd Average Speed Time Time Time Current May 17 11:59:06 volumio volumio[1469]: Dload Upload Total Spent Left Speed May 17 11:59:06 volumio volumio[889]: info: May 17 11:59:06 volumio volumio[889]: ---------------------------- Client requests Volume 43 May 17 11:59:06 volumio volumio[889]: info: VolumeController::SetAlsaVolume43 May 17 11:59:06 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:06 volumio volumio[1469]: [158B blob data] May 17 11:59:06 volumio volumio[1469]: {"time":1747454346271,"response":"volume Success"}43 May 17 11:59:06 volumio volumio[1469]: 48 May 17 11:59:06 volumio volumio[1469]: % Total % Received % Xferd Average Speed Time Time Time Current May 17 11:59:06 volumio volumio[1469]: Dload Upload Total Spent Left Speed May 17 11:59:06 volumio volumio[889]: info: May 17 11:59:06 volumio volumio[889]: ---------------------------- Client requests Volume 48 May 17 11:59:06 volumio volumio[889]: info: VolumeController::SetAlsaVolume48 May 17 11:59:06 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:06 volumio volumio[1469]: [158B blob data] May 17 11:59:06 volumio volumio[1469]: {"time":1747454346351,"response":"volume Success"}48 May 17 11:59:06 volumio volumio[1469]: 53 May 17 11:59:06 volumio volumio[1469]: % Total % Received % Xferd Average Speed Time Time Time Current May 17 11:59:06 volumio volumio[1469]: Dload Upload Total Spent Left Speed May 17 11:59:06 volumio volumio[889]: info: May 17 11:59:06 volumio volumio[889]: ---------------------------- Client requests Volume 53 May 17 11:59:06 volumio volumio[889]: info: VolumeController::SetAlsaVolume53 May 17 11:59:06 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:06 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:06 volumio volumio[1469]: [237B blob data] May 17 11:59:06 volumio volumio[1469]: {"time":1747454346433,"response":"volume Success"}53 May 17 11:59:07 volumio volumio[1469]: 58 May 17 11:59:07 volumio volumio[1469]: % Total % Received % Xferd Average Speed Time Time Time Current May 17 11:59:07 volumio volumio[1469]: Dload Upload Total Spent Left Speed May 17 11:59:07 volumio volumio[889]: info: May 17 11:59:07 volumio volumio[889]: ---------------------------- Client requests Volume 58 May 17 11:59:07 volumio volumio[889]: info: VolumeController::SetAlsaVolume58 May 17 11:59:07 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:07 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:07 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:07 volumio volumio[1469]: [158B blob data] May 17 11:59:07 volumio volumio[1469]: {"time":1747454347698,"response":"volume Success"}58 May 17 11:59:10 volumio volumio[889]: info: Clearing queue after UPNP request May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::ClearQueue May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::stop May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::setConsumeUpdateService undefined May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::stPlaybackTimer May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::updateTrackBlock May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrackBlock May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::serviceStop May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: ControllerMpd::stop May 17 11:59:11 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand stop May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::clearPlayQueue May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::saveQueue May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushQueue May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:11 volumio volumio[889]: info: sendMpdCommand stop took 14 milliseconds May 17 11:59:11 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:11 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:11 volumio volumio[889]: error: updateQueue error: null May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 2ms May 17 11:59:11 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand clearerror May 17 11:59:11 volumio volumio[889]: info: sendMpdCommand status took 4 milliseconds May 17 11:59:11 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:11 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: 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} May 17 11:59:11 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::syncState stateService stop May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:11 volumio volumio[889]: info: No code May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 29ms May 17 11:59:11 volumio volumio[889]: info: sendMpdCommand clearerror took 25 milliseconds May 17 11:59:11 volumio volumio[889]: info: Starting UPNP Playback May 17 11:59:11 volumio volumio[889]: info: Preparing playback through UPNP May 17 11:59:11 volumio volumio[889]: info: CoreCommandRouter::volumioGetState May 17 11:59:11 volumio volumio[889]: info: CorePlayQueue::getTrack 0 May 17 11:59:11 volumio volumio[889]: info: CoreStateMachine::setConsumeUpdateService mpd May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 2ms May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 0ms May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 0ms May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 1ms May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 1ms May 17 11:59:11 volumio volumio[889]: info: May 17 11:59:11 volumio volumio[889]: ---------------------------- MPD announces system playlist update May 17 11:59:11 volumio volumio[889]: info: Ignoring MPD Status Update May 17 11:59:11 volumio volumio[889]: info: ------------------------------ 0ms May 17 11:59:12 volumio volumio[889]: info: May 17 11:59:12 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:12 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:12 volumio volumio[889]: info: May 17 11:59:12 volumio volumio[889]: ---------------------------- MPD announces state update: player May 17 11:59:12 volumio volumio[889]: info: ControllerMpd::getState May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand status May 17 11:59:12 volumio volumio[889]: info: sendMpdCommand status took 3 milliseconds May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::parseState May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 17 11:59:12 volumio volumio[889]: info: sendMpdCommand playlistinfo took 1 milliseconds May 17 11:59:12 volumio volumio[889]: verbose: ControllerMpd::parseTrackInfo May 17 11:59:12 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:12 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:12 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"I Am You","artist":"Kim Taylor","album":"I Am You","uri":"http://m801.music.126.net/20250517122410/b2b193f7c57c63efcaf13fa112ff43f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28481789561/126c/bdf2/1a17/1f49a42d74854bb3ca07dcc19a52540d.mp3?vuutv=nfBORbb/hk4xM9A8IoCB+GJu2WccQ2SMN9/HlbxC+THrUxfMnurCAC45Medz6qkjibdpnv3guz9lXuOC9QGnsZ1pkaLEkzIue/KjBq4AQvc=","trackType":""} May 17 11:59:12 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus stop May 17 11:59:12 volumio volumio[889]: info: ControllerMpd::pushState May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::servicePushState May 17 11:59:12 volumio volumio[889]: verbose: In UPNP mode May 17 11:59:12 volumio volumio[889]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":274,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"I Am You","artist":"Kim Taylor","album":"I Am You","uri":"http://m801.music.126.net/20250517122410/b2b193f7c57c63efcaf13fa112ff43f0/jdymusic/obj/wo3DlMOGwrbDjj7DisKw/28481789561/126c/bdf2/1a17/1f49a42d74854bb3ca07dcc19a52540d.mp3?vuutv=nfBORbb/hk4xM9A8IoCB+GJu2WccQ2SMN9/HlbxC+THrUxfMnurCAC45Medz6qkjibdpnv3guz9lXuOC9QGnsZ1pkaLEkzIue/KjBq4AQvc=","trackType":""} May 17 11:59:12 volumio volumio[889]: verbose: CURRENT POSITION 0 May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::syncState stateService play May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::syncState currentStatus play May 17 11:59:12 volumio volumio[889]: info: Received an update from plugin. extracting info from payload May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::pushState May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::volumioPushState May 17 11:59:12 volumio volumio[889]: info: ------------------------------ 16ms May 17 11:59:12 volumio volumio[889]: info: ------------------------------ 19ms May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::volumioGetQueue May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::getQueue May 17 11:59:12 volumio volumio[889]: info: CorePlayQueue::getQueue May 17 11:59:12 volumio volumio[889]: info: CoreCommandRouter::volumioGetQueue May 17 11:59:12 volumio volumio[889]: info: CoreStateMachine::getQueue May 17 11:59:12 volumio volumio[889]: info: CorePlayQueue::getQueue May 17 11:59:37 volumio volumio[889]: info: CoreCommandRouter::volumioGetState May 17 11:59:40 volumio volumio[889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 11:59:40 volumio volumio[889]: Error: connect ETIMEDOUT 31.13.87.9:80 May 17 11:59:40 volumio volumio[889]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 17 11:59:40 volumio volumio[889]: errno: -110, May 17 11:59:40 volumio volumio[889]: code: 'ETIMEDOUT', May 17 11:59:40 volumio volumio[889]: syscall: 'connect', May 17 11:59:40 volumio volumio[889]: address: '31.13.87.9', May 17 11:59:40 volumio volumio[889]: port: 80 May 17 11:59:40 volumio volumio[889]: } May 17 11:59:40 volumio volumio[889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 17 11:59:40 volumio sudo[5853]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-17 11:58 May 17 11:59:40 volumio sudo[5853]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:50:12 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="39b0f8c200b2dcadf117e189b4b3632d"