-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2024-11-26 19:29:26 CET. -- Nov 26 19:28:14 volumio volumio[2815]: info: Nov 26 19:28:14 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand status took 0 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 5 Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 5 Nov 26 19:28:14 volumio volumio[2815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 26 19:28:14 volumio volumio[2815]: verbose: CURRENT POSITION 5 Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState stateService stop Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus play Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::play index undefined Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::startPlaybackTimer Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::clearAddPlayTracks NAS/flac2/Enigma - The Cross of Changes/07 Age of Loneliness (Carly’s Song).flac Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 19:28:14 volumio volumio[2815]: info: ------------------------------ 32ms Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand stop took 24 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 19:28:14 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:14 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:28:14 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:28:14 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:28:14 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:14 volumio volumio[2815]: info: Nov 26 19:28:14 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:28:14 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand clear took 38 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/07 Age of Loneliness (Carly’s Song).flac" Nov 26 19:28:14 volumio volumio[2815]: error: updateQueue error: null Nov 26 19:28:14 volumio volumio[2815]: info: Nov 26 19:28:14 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:28:14 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:28:14 volumio volumio[2815]: info: ------------------------------ 28ms Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/07 Age of Loneliness (Carly’s Song).flac" took 26 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand play Nov 26 19:28:14 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:14 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:14 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:14 volumio volumio[2815]: info: camilladsp spawned new process with pid 3928, instance 1, run: true Nov 26 19:28:14 volumio volumio[2815]: info: ------------------------------ 24ms Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand play took 22 milliseconds Nov 26 19:28:14 volumio volumio[2815]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 26 19:28:14 volumio volumio[2815]: info: Nov 26 19:28:14 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:14 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:14 volumio volumio[2815]: info: Nov 26 19:28:14 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand status took 28 milliseconds Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand status took 2 milliseconds Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::parseTrackInfo Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Age of Loneliness (Carly’s Song).flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/07 Age of Loneliness (Carly’s Song).flac","trackType":"flac"} Nov 26 19:28:14 volumio volumio[2815]: verbose: CURRENT POSITION 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState stateService play Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus stop Nov 26 19:28:14 volumio volumio[2815]: info: ------------------------------ 33ms Nov 26 19:28:14 volumio volumio[2815]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 26 19:28:14 volumio volumio[2815]: verbose: ControllerMpd::parseTrackInfo Nov 26 19:28:14 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":321,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"07 Age of Loneliness (Carly’s Song).flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/07 Age of Loneliness (Carly’s Song).flac","trackType":"flac"} Nov 26 19:28:14 volumio volumio[2815]: verbose: CURRENT POSITION 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState stateService play Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus play Nov 26 19:28:14 volumio volumio[2815]: info: Received an update from plugin. extracting info from payload Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:14 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:14 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:14 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:14 volumio volumio[2815]: info: ------------------------------ 36ms Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:15 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:15 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:15 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:15 volumio volumio[2815]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 26 19:28:51 volumio volumio[2815]: info: Nov 26 19:28:51 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:51 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:51 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:51 volumio volumio[2815]: info: sendMpdCommand status took 0 milliseconds Nov 26 19:28:51 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:51 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 6 Nov 26 19:28:51 volumio volumio[2815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 26 19:28:51 volumio volumio[2815]: verbose: CURRENT POSITION 6 Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::syncState stateService stop Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus play Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::play index undefined Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::startPlaybackTimer Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:51 volumio volumio[2815]: verbose: ControllerMpd::clearAddPlayTracks NAS/flac2/Enigma - The Cross of Changes/08 Out From the Deep.flac Nov 26 19:28:51 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 19:28:51 volumio volumio[2815]: info: ------------------------------ 30ms Nov 26 19:28:51 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:51 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:51 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:51 volumio volumio[2815]: info: sendMpdCommand stop took 22 milliseconds Nov 26 19:28:51 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 19:28:51 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:51 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:28:51 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:28:51 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:28:51 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: Nov 26 19:28:52 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:28:52 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand clear took 65 milliseconds Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/08 Out From the Deep.flac" Nov 26 19:28:52 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:52 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:52 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:28:52 volumio volumio[2815]: error: updateQueue error: null Nov 26 19:28:52 volumio volumio[2815]: info: Nov 26 19:28:52 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:28:52 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:28:52 volumio volumio[2815]: info: ------------------------------ 9ms Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/08 Out From the Deep.flac" took 8 milliseconds Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand play Nov 26 19:28:52 volumio volumio[2815]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 26 19:28:52 volumio volumio[2815]: info: camilladsp spawned new process with pid 4060, instance 1, run: true Nov 26 19:28:52 volumio volumio[2815]: info: ------------------------------ 22ms Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand play took 22 milliseconds Nov 26 19:28:52 volumio volumio[2815]: info: Nov 26 19:28:52 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:52 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: Nov 26 19:28:52 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand status took 14 milliseconds Nov 26 19:28:52 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand status took 1 milliseconds Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand playlistinfo took 1 milliseconds Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::parseTrackInfo Nov 26 19:28:52 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:52 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:52 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:52 volumio volumio[2815]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 Out From the Deep.flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/08 Out From the Deep.flac","trackType":"flac"} Nov 26 19:28:52 volumio volumio[2815]: verbose: CURRENT POSITION 7 Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::syncState stateService play Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus stop Nov 26 19:28:52 volumio volumio[2815]: info: ------------------------------ 18ms Nov 26 19:28:52 volumio volumio[2815]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 26 19:28:52 volumio volumio[2815]: verbose: ControllerMpd::parseTrackInfo Nov 26 19:28:52 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:28:52 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:28:52 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:52 volumio volumio[2815]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":293,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"08 Out From the Deep.flac","artist":null,"album":null,"uri":"NAS/flac2/Enigma - The Cross of Changes/08 Out From the Deep.flac","trackType":"flac"} Nov 26 19:28:52 volumio volumio[2815]: verbose: CURRENT POSITION 7 Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::syncState stateService play Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus play Nov 26 19:28:52 volumio volumio[2815]: info: Received an update from plugin. extracting info from payload Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:52 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:52 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:28:52 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:52 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:28:52 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:28:52 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:28:52 volumio volumio[2815]: info: ------------------------------ 34ms Nov 26 19:28:52 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:52 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:52 volumio volumio[2815]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 26 19:28:52 volumio volumio[2815]: info: touch_display: Setting screensaver timeout to 0 seconds. Nov 26 19:29:25 volumio volumio[2815]: info: Nov 26 19:29:25 volumio volumio[2815]: ---------------------------- MPD announces state update: player Nov 26 19:29:25 volumio volumio[2815]: info: ControllerMpd::getState Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand status Nov 26 19:29:25 volumio volumio[2815]: info: sendMpdCommand status took 1 milliseconds Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::parseState Nov 26 19:29:25 volumio volumio[2815]: info: ControllerMpd::pushState Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::servicePushState Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 7 Nov 26 19:29:25 volumio volumio[2815]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 26 19:29:25 volumio volumio[2815]: verbose: CURRENT POSITION 7 Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::syncState stateService stop Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::syncState currentStatus play Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::play index undefined Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 8 Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 8 Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::startPlaybackTimer Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 8 Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::clearAddPlayTracks NAS/flac2/Enigma - The Cross of Changes/09 The Cross of Changes.flac Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand stop Nov 26 19:29:25 volumio volumio[2815]: info: ------------------------------ 30ms Nov 26 19:29:25 volumio volumio[2815]: info: CoreStateMachine::pushState Nov 26 19:29:25 volumio volumio[2815]: info: CorePlayQueue::getTrack 8 Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 26 19:29:25 volumio volumio[2815]: info: CoreCommandRouter::volumioPushState Nov 26 19:29:25 volumio volumio[2815]: info: sendMpdCommand stop took 19 milliseconds Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand clear Nov 26 19:29:25 volumio volumio[2815]: info: [ASDebug] CurState: play PrevState: play Nov 26 19:29:25 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:29:25 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:29:25 volumio volumio[2815]: info: [ASDebug] CurState: stop PrevState: play Nov 26 19:29:25 volumio volumio[2815]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - volume level for loudness gain applied 23.00 Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 26 19:29:25 volumio volumio[2815]: info: FusionDsp - Loudness is ON true Nov 26 19:29:25 volumio volumio[2815]: info: Nov 26 19:29:25 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:29:25 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:29:25 volumio volumio[2815]: info: sendMpdCommand clear took 58 milliseconds Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/09 The Cross of Changes.flac" Nov 26 19:29:25 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:29:25 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:29:25 volumio volumio[2815]: error: FusionDsp - WebSocket error: [object Object] Nov 26 19:29:25 volumio volumio[2815]: error: updateQueue error: null Nov 26 19:29:25 volumio volumio[2815]: info: Nov 26 19:29:25 volumio volumio[2815]: ---------------------------- MPD announces system playlist update Nov 26 19:29:25 volumio volumio[2815]: info: Ignoring MPD Status Update Nov 26 19:29:25 volumio volumio[2815]: info: ------------------------------ 9ms Nov 26 19:29:25 volumio volumio[2815]: info: sendMpdCommand add "NAS/flac2/Enigma - The Cross of Changes/09 The Cross of Changes.flac" took 6 milliseconds Nov 26 19:29:25 volumio volumio[2815]: verbose: ControllerMpd::sendMpdCommand play Nov 26 19:29:25 volumio volumio[2815]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 26 19:29:25 volumio volumio[2815]: info: camilladsp spawned new process with pid 4147, instance 1, run: true Nov 26 19:29:25 volumio volumio[2815]: info: ------------------------------ 21ms Nov 26 19:29:25 volumio volumio[2815]: info: sendMpdCommand play took 20 milliseconds Nov 26 19:29:26 volumio volumio[2815]: info: [ASDebug] Togle GPIO: OFF Nov 26 19:29:26 volumio volumio[2815]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 19:29:26 volumio volumio[2815]: Error: ESPIPE: invalid seek, write Nov 26 19:29:26 volumio volumio[2815]: at Object.writeSync (fs.js:711:3) Nov 26 19:29:26 volumio volumio[2815]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 26 19:29:26 volumio volumio[2815]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Nov 26 19:29:26 volumio volumio[2815]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Nov 26 19:29:26 volumio volumio[2815]: at listOnTimeout (internal/timers.js:554:17) Nov 26 19:29:26 volumio volumio[2815]: at processTimers (internal/timers.js:497:7) { Nov 26 19:29:26 volumio volumio[2815]: errno: -29, Nov 26 19:29:26 volumio volumio[2815]: syscall: 'write', Nov 26 19:29:26 volumio volumio[2815]: code: 'ESPIPE' Nov 26 19:29:26 volumio volumio[2815]: } Nov 26 19:29:26 volumio volumio[2815]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 26 19:29:26 volumio sudo[4163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-26 19:28 Nov 26 19:29:26 volumio sudo[4163]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"