-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Thu 2024-10-31 12:59:20 CST. -- Oct 31 12:58:43 volumio volumio[1901]: 33 Oct 31 12:58:43 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:58:43 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:58:43 volumio volumio[967]: info: Oct 31 12:58:43 volumio volumio[967]: ---------------------------- Client requests Volume 33 Oct 31 12:58:43 volumio volumio[967]: info: VolumeController::SetAlsaVolume33 Oct 31 12:58:43 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:43 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:58:43 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:43 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:43 volumio volumio[1901]: [158B blob data] Oct 31 12:58:43 volumio volumio[1901]: {"time":1730350723648,"response":"volume Success"}33 Oct 31 12:58:43 volumio volumio[967]: info: Clearing queue after UPNP request Oct 31 12:58:44 volumio volumio[967]: info: CoreStateMachine::ClearQueue Oct 31 12:58:44 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:58:44 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:58:44 volumio volumio[967]: info: CorePlayQueue::clearPlayQueue Oct 31 12:58:44 volumio volumio[967]: info: CorePlayQueue::saveQueue Oct 31 12:58:44 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:44 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Oct 31 12:58:44 volumio volumio[967]: error: Upnp client error: Error: This socket has been ended by the other party Oct 31 12:58:45 volumio volumio[967]: info: Starting UPNP Playback Oct 31 12:58:45 volumio volumio[967]: info: Preparing playback through UPNP Oct 31 12:58:45 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Oct 31 12:58:45 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:58:45 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 25ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 18ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 16ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 14ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 13ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 11ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 8ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 7ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 12ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 11ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 9ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 6ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 11ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 9ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 8ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 10ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 9ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 7ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 6ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:58:46 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 7ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 6ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 31 12:58:46 volumio volumio[967]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 31 12:58:46 volumio volumio[967]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 65 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 62 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 61 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 57 milliseconds Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: Oct 31 12:58:46 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 10 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 6 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 4 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand status took 3 milliseconds Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 105ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 134ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 132ms Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 63 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 31 12:58:46 volumio volumio[967]: info: sendMpdCommand playlistinfo took 61 milliseconds Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:58:46 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:58:46 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:58:46 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:58:46 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:46 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 145ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 138ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 137ms Oct 31 12:58:46 volumio volumio[967]: info: ------------------------------ 137ms Oct 31 12:58:46 volumio volumio[967]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 31 12:58:59 volumio volumio[1901]: 34 Oct 31 12:58:59 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:58:59 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:58:59 volumio volumio[967]: info: Oct 31 12:58:59 volumio volumio[967]: ---------------------------- Client requests Volume 34 Oct 31 12:58:59 volumio volumio[967]: info: VolumeController::SetAlsaVolume34 Oct 31 12:58:59 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:59 volumio volumio[1901]: [158B blob data] Oct 31 12:58:59 volumio volumio[1901]: {"time":1730350739425,"response":"volume Success"}34 Oct 31 12:58:59 volumio volumio[1901]: 36 Oct 31 12:58:59 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:58:59 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:58:59 volumio volumio[967]: info: Oct 31 12:58:59 volumio volumio[967]: ---------------------------- Client requests Volume 36 Oct 31 12:58:59 volumio volumio[967]: info: VolumeController::SetAlsaVolume36 Oct 31 12:58:59 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:59 volumio volumio[1901]: [158B blob data] Oct 31 12:58:59 volumio volumio[1901]: {"time":1730350739517,"response":"volume Success"}36 Oct 31 12:58:59 volumio volumio[1901]: 37 Oct 31 12:58:59 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:58:59 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:58:59 volumio volumio[967]: info: Oct 31 12:58:59 volumio volumio[967]: ---------------------------- Client requests Volume 37 Oct 31 12:58:59 volumio volumio[967]: info: VolumeController::SetAlsaVolume37 Oct 31 12:58:59 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:59 volumio volumio[1901]: [158B blob data] Oct 31 12:58:59 volumio volumio[1901]: {"time":1730350739609,"response":"volume Success"}37 Oct 31 12:58:59 volumio volumio[1901]: 36 Oct 31 12:58:59 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:58:59 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:58:59 volumio volumio[967]: info: Oct 31 12:58:59 volumio volumio[967]: ---------------------------- Client requests Volume 36 Oct 31 12:58:59 volumio volumio[967]: info: VolumeController::SetAlsaVolume36 Oct 31 12:58:59 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:58:59 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:58:59 volumio volumio[1901]: [158B blob data] Oct 31 12:58:59 volumio volumio[1901]: {"time":1730350739815,"response":"volume Success"}36 Oct 31 12:59:01 volumio volumio[1901]: 38 Oct 31 12:59:01 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:59:01 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:59:01 volumio volumio[967]: info: Oct 31 12:59:01 volumio volumio[967]: ---------------------------- Client requests Volume 38 Oct 31 12:59:01 volumio volumio[967]: info: VolumeController::SetAlsaVolume38 Oct 31 12:59:01 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:01 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:01 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:01 volumio volumio[1901]: [158B blob data] Oct 31 12:59:01 volumio volumio[1901]: {"time":1730350741121,"response":"volume Success"}38 Oct 31 12:59:01 volumio volumio[1901]: 39 Oct 31 12:59:01 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:59:01 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:59:01 volumio volumio[967]: info: Oct 31 12:59:01 volumio volumio[967]: ---------------------------- Client requests Volume 39 Oct 31 12:59:01 volumio volumio[967]: info: VolumeController::SetAlsaVolume39 Oct 31 12:59:01 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:01 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:01 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:01 volumio volumio[1901]: [158B blob data] Oct 31 12:59:01 volumio volumio[1901]: {"time":1730350741207,"response":"volume Success"}39 Oct 31 12:59:08 volumio volumio[967]: info: Oct 31 12:59:08 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:08 volumio volumio[967]: info: Oct 31 12:59:08 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:08 volumio volumio[967]: info: Oct 31 12:59:08 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:08 volumio volumio[967]: info: Oct 31 12:59:08 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand status took 14 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand status took 11 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand status took 8 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand status took 6 milliseconds Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 31 12:59:08 volumio volumio[967]: info: sendMpdCommand playlistinfo took 7 milliseconds Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:08 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: 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":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:59:08 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stPlaybackTimer Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: 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":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:59:08 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: No code Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: 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":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:59:08 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: No code Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: 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":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:55781/stream-0.flc","trackType":"flc"} Oct 31 12:59:08 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState stateService stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: No code Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:08 volumio volumio[967]: info: ------------------------------ 139ms Oct 31 12:59:08 volumio volumio[967]: info: ------------------------------ 136ms Oct 31 12:59:08 volumio volumio[967]: info: ------------------------------ 133ms Oct 31 12:59:08 volumio volumio[967]: info: ------------------------------ 130ms Oct 31 12:59:08 volumio volumio[967]: info: Airplay started streaming, receiving metadatas Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioStop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: Airplay started streaming, receiving metadatas Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioStop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: Airplay started streaming, receiving metadatas Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioStop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: Airplay started streaming, receiving metadatas Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioStop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio shairport-sync[1649]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:59:08 volumio shairport-sync[1649]: Dload Upload Total Spent Left Speed Oct 31 12:59:08 volumio volumio[967]: info: Oct 31 12:59:08 volumio volumio[967]: ---------------------------- Client requests Start Airplay PlaybackRoutine Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Oct 31 12:59:08 volumio volumio[967]: info: Airplay playback start Oct 31 12:59:08 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:08 volumio volumio[967]: info: CoreCommandRouter::volumioStop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:08 volumio shairport-sync[1649]: [158B blob data] Oct 31 12:59:09 volumio volumio[967]: info: camilladsp spawned new process with pid 17696, instance 1, run: true Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:09 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:09 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:09 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:09 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:09 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:09 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:10 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:10 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:10 volumio volumio[967]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 31 12:59:10 volumio volumio[967]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 31 12:59:10 volumio volumio[967]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 31 12:59:10 volumio volumio[967]: info: FusionDsp - {"Reload":{"result":"Ok"}} Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio shairport-sync[1649]: {"time":1730350748878,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:59:17 volumio shairport-sync[1649]: Dload Upload Total Spent Left Speed Oct 31 12:59:17 volumio volumio[967]: info: Oct 31 12:59:17 volumio volumio[967]: ---------------------------- Client requests Stop Airplay Playback Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Oct 31 12:59:17 volumio volumio[967]: info: Airplay playback stop Oct 31 12:59:17 volumio volumio[967]: info: Airplay Playback Stopped, clearing state Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::resetVolumioState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::getcurrentVolume Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 12:59:17 volumio shairport-sync[1649]: [158B blob data] Oct 31 12:59:17 volumio volumio[967]: info: VolumeController:: Volume=38 Mute =false Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::updateTrackBlock Oct 31 12:59:17 volumio volumio[967]: info: CorePlayQueue::getTrackBlock Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Oct 31 12:59:17 volumio volumio[1901]: 33 Oct 31 12:59:17 volumio volumio[967]: info: VolumeController:: Volume=38 Mute =false Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[1901]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 31 12:59:17 volumio volumio[1901]: Dload Upload Total Spent Left Speed Oct 31 12:59:17 volumio volumio[967]: info: Oct 31 12:59:17 volumio volumio[967]: ---------------------------- Client requests Volume 33 Oct 31 12:59:17 volumio volumio[967]: info: VolumeController::SetAlsaVolume33 Oct 31 12:59:17 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:17 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:17 volumio volumio[1901]: [158B blob data] Oct 31 12:59:17 volumio volumio[1901]: {"time":1730350757832,"response":"volume Success"}33 Oct 31 12:59:18 volumio volumio[967]: info: Clearing queue after UPNP request Oct 31 12:59:18 volumio volumio[967]: verbose: UNSET VOLATILE: Service: airplay_emulation Oct 31 12:59:18 volumio volumio[967]: info: Stopping Airplay Playback and sending pause command to client via USR2 Oct 31 12:59:18 volumio sudo[17726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1649 Oct 31 12:59:18 volumio sudo[17726]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 12:59:18 volumio sudo[17726]: pam_unix(sudo:session): session closed for user root Oct 31 12:59:18 volumio volumio[967]: info: Shairport-Sync paused with USR2 Oct 31 12:59:18 volumio volumio[967]: info: CoreStateMachine::ClearQueue Oct 31 12:59:18 volumio volumio[967]: info: CoreStateMachine::stop Oct 31 12:59:18 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 31 12:59:18 volumio volumio[967]: info: CorePlayQueue::clearPlayQueue Oct 31 12:59:18 volumio volumio[967]: info: CorePlayQueue::saveQueue Oct 31 12:59:18 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:18 volumio volumio[967]: info: CoreCommandRouter::volumioPushQueue Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: error: updateQueue error: null Oct 31 12:59:18 volumio volumio[967]: error: updateQueue error: null Oct 31 12:59:18 volumio volumio[967]: error: updateQueue error: null Oct 31 12:59:18 volumio volumio[967]: error: updateQueue error: null Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 8ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 7ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 6ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: camilladsp spawned new process with pid 17728, instance 1, run: true Oct 31 12:59:18 volumio volumio[967]: info: Starting UPNP Playback Oct 31 12:59:18 volumio volumio[967]: info: Preparing playback through UPNP Oct 31 12:59:18 volumio volumio[967]: info: CoreCommandRouter::volumioGetState Oct 31 12:59:18 volumio volumio[967]: info: CorePlayQueue::getTrack 0 Oct 31 12:59:18 volumio volumio[967]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 6ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 4ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: Oct 31 12:59:18 volumio volumio[967]: ---------------------------- MPD announces system playlist update Oct 31 12:59:18 volumio volumio[967]: info: Ignoring MPD Status Update Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 5ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 3ms Oct 31 12:59:18 volumio volumio[967]: info: ------------------------------ 2ms Oct 31 12:59:19 volumio volumio[967]: info: Restarting Shairport-Sync after stop Oct 31 12:59:19 volumio sudo[17743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 31 12:59:19 volumio sudo[17743]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 31 12:59:19 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 31 12:59:19 volumio shairport-sync[1649]: {"time":1730350757602,"response":"stopAirplayPlayback Success"} Oct 31 12:59:19 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 31 12:59:19 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 31 12:59:19 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 31 12:59:19 volumio sudo[17743]: pam_unix(sudo:session): session closed for user root Oct 31 12:59:19 volumio volumio[967]: info: Shairport-Sync retarted Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 16 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 13 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 9 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 6 milliseconds Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: Oct 31 12:59:19 volumio volumio[967]: ---------------------------- MPD announces state update: player Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::getState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand status Oct 31 12:59:19 volumio volumio[967]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 31 12:59:19 volumio volumio[967]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 31 12:59:19 volumio volumio[967]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 35 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 33 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 31 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 30 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand status took 30 milliseconds Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseState Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus stop Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":455,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":455,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":455,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 82ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 110ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 107ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 104ms Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 68 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 66 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 31 12:59:19 volumio volumio[967]: info: sendMpdCommand playlistinfo took 66 milliseconds Oct 31 12:59:19 volumio volumio[967]: verbose: ControllerMpd::parseTrackInfo Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1206,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ControllerMpd::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::servicePushState Oct 31 12:59:19 volumio volumio[967]: verbose: In UPNP mode Oct 31 12:59:19 volumio volumio[967]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1343,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Streaming from AirConnect","artist":null,"album":null,"uri":"http://192.168.50.100:39901/stream-1.flc","trackType":"flc"} Oct 31 12:59:19 volumio volumio[967]: verbose: CURRENT POSITION 0 Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState stateService play Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::syncState currentStatus play Oct 31 12:59:19 volumio volumio[967]: info: Received an update from plugin. extracting info from payload Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: CoreStateMachine::pushState Oct 31 12:59:19 volumio volumio[967]: info: CoreCommandRouter::volumioPushState Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 159ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 154ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 153ms Oct 31 12:59:19 volumio volumio[967]: info: ------------------------------ 152ms Oct 31 12:59:19 volumio volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 12:59:19 volumio volumio[967]: Error: Client network socket disconnected before secure TLS connection was established Oct 31 12:59:19 volumio volumio[967]: at connResetException (internal/errors.js:639:14) Oct 31 12:59:19 volumio volumio[967]: at TLSSocket.onConnectEnd (_tls_wrap.js:1570:19) Oct 31 12:59:19 volumio volumio[967]: at TLSSocket.emit (events.js:412:35) Oct 31 12:59:19 volumio volumio[967]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 31 12:59:19 volumio volumio[967]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Oct 31 12:59:19 volumio volumio[967]: code: 'ECONNRESET', Oct 31 12:59:19 volumio volumio[967]: path: null, Oct 31 12:59:19 volumio volumio[967]: host: 'lastfm.freetls.fastly.net', Oct 31 12:59:19 volumio volumio[967]: port: 443, Oct 31 12:59:19 volumio volumio[967]: localAddress: undefined Oct 31 12:59:19 volumio volumio[967]: } Oct 31 12:59:19 volumio volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 31 12:59:20 volumio sudo[17760]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-31 12:58 Oct 31 12:59:20 volumio sudo[17760]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"