-- 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"