-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Tue 2024-07-23 18:55:52 +07. -- Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::updateTrackBlock Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrackBlock Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::stPlaybackTimer Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::serviceStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::serviceStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioStop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Jul 23 18:54:04 volumio-pi4 volumio[1039]: ---------------------------- MPD announces state update: player Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand stop took 16 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::getState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Jul 23 18:54:04 volumio-pi4 volumio[1039]: ---------------------------- MPD announces state update: player Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::getState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Jul 23 18:54:04 volumio-pi4 shairport-sync[1237]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 23 18:54:04 volumio-pi4 shairport-sync[1237]: Dload Upload Total Spent Left Speed Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Jul 23 18:54:04 volumio-pi4 volumio[1039]: ---------------------------- MPD announces state update: player Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::getState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: Jul 23 18:54:04 volumio-pi4 volumio[1039]: ---------------------------- MPD announces state update: player Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::getState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand status took 6 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand status took 4 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand status took 3 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand status took 2 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseState Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand playlistinfo took 2 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: sendMpdCommand playlistinfo took 3 milliseconds Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseTrackInfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseTrackInfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseTrackInfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: ControllerMpd::parseTrackInfo Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: 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":"100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","artist":null,"album":null,"uri":"NAS/100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","trackType":"mp3"} Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: CURRENT POSITION 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState stateService stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: No code Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: 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":"100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","artist":null,"album":null,"uri":"NAS/100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","trackType":"mp3"} Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: CURRENT POSITION 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState stateService stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: No code Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: 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":"100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","artist":null,"album":null,"uri":"NAS/100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","trackType":"mp3"} Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: CURRENT POSITION 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState stateService stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: No code Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ControllerMpd::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: 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":"100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","artist":null,"album":null,"uri":"NAS/100 Bản Nhạc Hoa Không Lời Hay Nhất - Nhạc Không Lời Nhẹ Nhàng, Sâu Lắng.mp3","trackType":"mp3"} Jul 23 18:54:04 volumio-pi4 volumio[1039]: verbose: CURRENT POSITION 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState stateService stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: No code Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ------------------------------ 90ms Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ------------------------------ 87ms Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ------------------------------ 86ms Jul 23 18:54:04 volumio-pi4 volumio[1039]: info: ------------------------------ 86ms Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: Jul 23 18:54:05 volumio-pi4 volumio[1039]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: Airplay playback start Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrack 0 Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioStop Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::stop Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 23 18:54:05 volumio-pi4 shairport-sync[1237]: [158B blob data] Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:05 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 18:54:06 volumio-pi4 volumio[1039]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 18:54:15 volumio-pi4 volumio[1039]: info: VolumeController::SetAlsaVolume65 Jul 23 18:54:15 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:15 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:15 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:24 volumio-pi4 volumio[1039]: info: VolumeController::SetAlsaVolume24 Jul 23 18:54:24 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:24 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:24 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:25 volumio-pi4 volumio[1039]: info: VolumeController::SetAlsaVolume58 Jul 23 18:54:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:26 volumio-pi4 volumio[1039]: info: VolumeController::SetAlsaVolume92 Jul 23 18:54:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:54:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:54:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:54:35 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 23 18:54:36 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 18:54:44 volumio-pi4 sudo[1468]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 18:54:44 volumio-pi4 sudo[1468]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:44 volumio-pi4 sudo[1468]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 sudo[1471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 18:54:44 volumio-pi4 sudo[1471]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:44 volumio-pi4 volumio[1039]: info: MPD Permissions set Jul 23 18:54:44 volumio-pi4 systemd[1]: Stopping Music Player Daemon... Jul 23 18:54:44 volumio-pi4 systemd[1]: mpd.service: Succeeded. Jul 23 18:54:44 volumio-pi4 systemd[1]: Stopped Music Player Daemon. Jul 23 18:54:45 volumio-pi4 systemd[1]: Starting Music Player Daemon... Jul 23 18:54:45 volumio-pi4 sudo[1477]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 18:54:45 volumio-pi4 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:45 volumio-pi4 sudo[1477]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:45 volumio-pi4 mpd[1479]: Jul 23 18:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 18:54:45 volumio-pi4 systemd[1]: Started Music Player Daemon. Jul 23 18:54:45 volumio-pi4 sudo[1471]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:45 volumio-pi4 volumio[1039]: error: updateQueue error: null Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 18:54:52 volumio-pi4 sudo[1522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 18:54:52 volumio-pi4 sudo[1522]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 sudo[1522]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:52 volumio-pi4 sudo[1525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 18:54:52 volumio-pi4 sudo[1525]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:52 volumio-pi4 volumio[1039]: info: MPD Permissions set Jul 23 18:54:52 volumio-pi4 systemd[1]: Stopping Music Player Daemon... Jul 23 18:54:52 volumio-pi4 systemd[1]: mpd.service: Succeeded. Jul 23 18:54:52 volumio-pi4 systemd[1]: Stopped Music Player Daemon. Jul 23 18:54:52 volumio-pi4 systemd[1]: Starting Music Player Daemon... Jul 23 18:54:52 volumio-pi4 sudo[1531]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 18:54:52 volumio-pi4 sudo[1531]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:52 volumio-pi4 sudo[1531]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:53 volumio-pi4 mpd[1534]: Jul 23 18:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 18:54:53 volumio-pi4 systemd[1]: Started Music Player Daemon. Jul 23 18:54:53 volumio-pi4 sudo[1525]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:53 volumio-pi4 volumio[1039]: error: updateQueue error: null Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Jul 23 18:54:57 volumio-pi4 sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 23 18:54:57 volumio-pi4 sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 sudo[1538]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 23 18:54:57 volumio-pi4 sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:57 volumio-pi4 systemd[1]: Stopping Music Player Daemon... Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 23 18:54:57 volumio-pi4 volumio[1039]: info: MPD Permissions set Jul 23 18:54:57 volumio-pi4 systemd[1]: mpd.service: Succeeded. Jul 23 18:54:57 volumio-pi4 systemd[1]: Stopped Music Player Daemon. Jul 23 18:54:57 volumio-pi4 systemd[1]: Starting Music Player Daemon... Jul 23 18:54:57 volumio-pi4 sudo[1547]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 23 18:54:57 volumio-pi4 sudo[1547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:54:57 volumio-pi4 sudo[1547]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:58 volumio-pi4 mpd[1549]: Jul 23 18:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 23 18:54:58 volumio-pi4 systemd[1]: Started Music Player Daemon. Jul 23 18:54:58 volumio-pi4 sudo[1540]: pam_unix(sudo:session): session closed for user root Jul 23 18:54:58 volumio-pi4 volumio[1039]: error: updateQueue error: null Jul 23 18:55:03 volumio-pi4 ntpd[761]: Soliciting pool server 2001:ac8:81:65:0:2:0:3 Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:04 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 shairport-sync[1237]: {"time":1721735645013,"response":"startAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 23 18:55:11 volumio-pi4 shairport-sync[1237]: Dload Upload Total Spent Left Speed Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: Jul 23 18:55:11 volumio-pi4 volumio[1039]: ---------------------------- Client requests Stop Airplay Playback Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , stopAirplayPlayback Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: Airplay playback stop Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: Airplay Playback Stopped, clearing state Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::resetVolumioState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::getcurrentVolume Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Jul 23 18:55:11 volumio-pi4 shairport-sync[1237]: [237B blob data] Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: VolumeController::SetAlsaVolume69 Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: VolumeController:: Volume=92 Mute =false Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::updateTrackBlock Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CorePlayQueue::getTrackBlock Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioRetrievevolume Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: VolumeController:: Volume=69 Mute =false Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:11 volumio-pi4 volumio[1039]: info: camilladsp spawned new process with pid 1560, instance 1, run: true Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: FusionDsp - Effects disabled Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: CoreCommandRouter::Close All Modals sent Jul 23 18:55:14 volumio-pi4 volumio[1039]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 18:55:20 volumio-pi4 volumio[1039]: info: CALLMETHOD: audio_interface fusiondsp enableeffect Jul 23 18:55:20 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Jul 23 18:55:21 volumio-pi4 volumio[1039]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 18:55:21 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:21 volumio-pi4 volumio[1039]: info: CoreCommandRouter::Close All Modals sent Jul 23 18:55:21 volumio-pi4 volumio[1039]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 18:55:22 volumio-pi4 volumio[1039]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jul 23 18:55:22 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jul 23 18:55:23 volumio-pi4 volumio[1039]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 18:55:23 volumio-pi4 volumio[1039]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 18:55:23 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:23 volumio-pi4 volumio[1039]: info: CoreCommandRouter::Close All Modals sent Jul 23 18:55:24 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:55:24 volumio-pi4 volumio[1039]: info: Airplay started streaming, receiving metadatas Jul 23 18:55:24 volumio-pi4 shairport-sync[1237]: {"time":1721735711212,"response":"stopAirplayPlayback Success"} % Total % Received % Xferd Average Speed Time Time Time Current Jul 23 18:55:24 volumio-pi4 shairport-sync[1237]: Dload Upload Total Spent Left Speed Jul 23 18:55:24 volumio-pi4 volumio[1039]: info: Jul 23 18:55:24 volumio-pi4 volumio[1039]: ---------------------------- Client requests Start Airplay PlaybackRoutine Jul 23 18:55:24 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: airplay_emulation , startAirplayPlayback Jul 23 18:55:24 volumio-pi4 volumio[1039]: info: Airplay playback start Jul 23 18:55:24 volumio-pi4 shairport-sync[1237]: [158B blob data] Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:25 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 shairport-sync[1237]: {"time":1721735724625,"response":"startAirplayPlayback Success"}ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::servicePushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreStateMachine::pushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioPushState Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: FusionDsp - ---- read samplerate from file: 44100 Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jul 23 18:55:26 volumio-pi4 volumio[1039]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jul 23 18:55:31 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioGetState Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Retrieving Cloud Streaming UI Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Getting Tidal Cloud Configuration Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Getting Qobuz Cloud Configuration Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Asking plugin for UI Config Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Getting Spotify Cloud Configuration Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Asking plugin for UI Config Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Saving Spotify Acccount Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Got Tidal Cloud Configuration Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Got it Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: Got it Jul 23 18:55:37 volumio-pi4 volumio[1039]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 23 18:55:37 volumio-pi4 volumio[1039]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::volumioGetBrowseSources Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 18:55:37 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jul 23 18:55:41 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 18:55:45 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 18:55:49 volumio-pi4 volumio[1039]: info: Disabling MyMusic plugin upnp Jul 23 18:55:49 volumio-pi4 sudo[1622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jul 23 18:55:49 volumio-pi4 sudo[1622]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 23 18:55:49 volumio-pi4 systemd[1]: Stopping UPnP Renderer front-end to MPD... Jul 23 18:55:49 volumio-pi4 volumio[1039]: error: Upnp client error: Error: This socket has been ended by the other party Jul 23 18:55:49 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jul 23 18:55:52 volumio-pi4 volumio[1039]: info: Enabling MyMusic plugin upnp Jul 23 18:55:52 volumio-pi4 volumio[1039]: info: Enabling plugin upnp Jul 23 18:55:52 volumio-pi4 volumio[1039]: info: Loading plugin "upnp"... Jul 23 18:55:52 volumio-pi4 volumio[1039]: info: [1721735752143] Starting Upmpd Daemon Jul 23 18:55:52 volumio-pi4 volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 23 18:55:52 volumio-pi4 volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 18:55:52 volumio-pi4 volumio[1039]: Error: listen EADDRINUSE: address already in use :::6599 Jul 23 18:55:52 volumio-pi4 volumio[1039]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at listenInCluster (net.js:1366:12) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at Server.listen (net.js:1452:7) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jul 23 18:55:52 volumio-pi4 volumio[1039]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 23 18:55:52 volumio-pi4 volumio[1039]: code: 'EADDRINUSE', Jul 23 18:55:52 volumio-pi4 volumio[1039]: errno: -98, Jul 23 18:55:52 volumio-pi4 volumio[1039]: syscall: 'listen', Jul 23 18:55:52 volumio-pi4 volumio[1039]: address: '::', Jul 23 18:55:52 volumio-pi4 volumio[1039]: port: 6599 Jul 23 18:55:52 volumio-pi4 volumio[1039]: } Jul 23 18:55:52 volumio-pi4 volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 23 18:55:52 volumio-pi4 sudo[1633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-23 18:54 Jul 23 18:55:52 volumio-pi4 sudo[1633]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"