-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Thu 2025-07-10 17:28:09 CEST. -- Jul 10 17:27:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:27:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:27:00 volumio volumio[1079]: info: Discovery: Getting this device information Jul 10 17:27:00 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Jul 10 17:27:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:27:02 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:27:02 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: false Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: true Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:27:02 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:27:04 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:27:04 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: false Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: false Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:27:04 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::volumioStop Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::stop Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::stPlaybackTimer Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::updateTrackBlock Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrackBlock Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::serviceStop Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::serviceStop Jul 10 17:27:19 volumio volumio[1079]: info: [1752161239440] ControllerWebradio::stop Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] CurState: stop PrevState: play Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 10 17:27:19 volumio volumio[1079]: info: Jul 10 17:27:19 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand stop took 15 milliseconds Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:19 volumio volumio[1079]: info: Jul 10 17:27:19 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:19 volumio volumio[1079]: info: Jul 10 17:27:19 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand status took 1 milliseconds Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand status took 1 milliseconds Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand status took 0 milliseconds Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 17:27:19 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 1 milliseconds Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:19 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 17:27:19 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:19 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:19 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:19 volumio volumio[1079]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jul 10 17:27:19 volumio volumio[1079]: info: ------------------------------ 25ms Jul 10 17:27:19 volumio volumio[1079]: info: ------------------------------ 24ms Jul 10 17:27:19 volumio volumio[1079]: info: ------------------------------ 24ms Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] CurState: stop PrevState: play Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] CurState: stop PrevState: play Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] CurState: stop PrevState: play Jul 10 17:27:19 volumio volumio[1079]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jul 10 17:27:19 volumio volumio[1079]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 10 17:27:20 volumio volumio[1079]: info: [ASDebug] Togle GPIO: OFF Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPlay Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::play index undefined Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::startPlaybackTimer Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: info: [1752161242857] ControllerWebradio::clearAddPlayTrack Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand stop took 1 milliseconds Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand clear took 0 milliseconds Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand load "https://orf-live.ors-shoutcast.at/oe3-q2a" Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand add "https://orf-live.ors-shoutcast.at/oe3-q2a" Jul 10 17:27:22 volumio volumio[1079]: error: updateQueue error: null Jul 10 17:27:22 volumio volumio[1079]: error: updateQueue error: null Jul 10 17:27:22 volumio volumio[1079]: error: updateQueue error: null Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 48ms Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 48ms Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 4ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand add "https://orf-live.ors-shoutcast.at/oe3-q2a" took 3 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand play Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 2ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand play took 1 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 1ms Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 1ms Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 11 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 12 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 11 milliseconds Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces system playlist update Jul 10 17:27:22 volumio volumio[1079]: info: Ignoring MPD Status Update Jul 10 17:27:22 volumio volumio[1079]: info: Jul 10 17:27:22 volumio volumio[1079]: ---------------------------- MPD announces state update: player Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::getState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand status Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 4ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 4 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 5 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 5ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 4 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 4ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand status took 4 milliseconds Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseState Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus stop Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":252,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus play Jul 10 17:27:22 volumio volumio[1079]: info: Received an update from plugin. extracting info from payload Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":703,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus play Jul 10 17:27:22 volumio volumio[1079]: info: Received an update from plugin. extracting info from payload Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 24ms Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 37ms Jul 10 17:27:22 volumio volumio[1079]: info: ------------------------------ 37ms Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 10 17:27:22 volumio volumio[1079]: info: sendMpdCommand playlistinfo took 20 milliseconds Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:22 volumio volumio[1079]: verbose: ControllerMpd::parseTrackInfo Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus play Jul 10 17:27:22 volumio volumio[1079]: info: Received an update from plugin. extracting info from payload Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus play Jul 10 17:27:22 volumio volumio[1079]: info: Received an update from plugin. extracting info from payload Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:22 volumio volumio[1079]: info: ControllerMpd::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::servicePushState Jul 10 17:27:22 volumio volumio[1079]: info: CorePlayQueue::getTrack 0 Jul 10 17:27:22 volumio volumio[1079]: verbose: STATE SERVICE {"status":"play","position":0,"seek":986,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"HITRADIO Ö3 - Livestream","artist":null,"album":null,"uri":"https://orf-live.ors-shoutcast.at/oe3-q2a","trackType":"at/oe3-q2a"} Jul 10 17:27:22 volumio volumio[1079]: verbose: CURRENT POSITION 0 Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState stateService play Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::syncState currentStatus play Jul 10 17:27:22 volumio volumio[1079]: info: Received an update from plugin. extracting info from payload Jul 10 17:27:22 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:22 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:23 volumio volumio[1079]: info: CoreStateMachine::pushState Jul 10 17:27:23 volumio volumio[1079]: info: CoreCommandRouter::volumioPushState Jul 10 17:27:23 volumio volumio[1079]: info: ------------------------------ 54ms Jul 10 17:27:23 volumio volumio[1079]: info: ------------------------------ 53ms Jul 10 17:27:23 volumio volumio[1079]: info: ------------------------------ 52ms Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: stop Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] Togle GPIO: ON Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:23 volumio volumio[1079]: info: [ASDebug] CurState: play PrevState: play Jul 10 17:27:26 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:27:26 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: true Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: false Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:27:26 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:27:30 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:27:30 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:27:30 volumio volumio[1079]: info: Discovery: Getting this device information Jul 10 17:27:30 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Jul 10 17:27:30 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:27:42 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:27:42 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: true Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: true Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:27:42 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:27:50 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:27:50 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: false Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: true Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:27:50 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:28:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 17:28:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 17:28:00 volumio volumio[1079]: info: Discovery: Getting this device information Jul 10 17:28:00 volumio volumio[1079]: info: CoreCommandRouter::volumioGetState Jul 10 17:28:00 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 17:28:01 volumio volumio[1079]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Jul 10 17:28:01 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: Port: 4 Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: Inverted: false Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: Delay: 720 Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: Latched: false Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: On Pulse width: 500 Jul 10 17:28:01 volumio volumio[1079]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Jul 10 17:28:03 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 10 17:28:03 volumio volumio[1079]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 10 17:28:07 volumio volumio[1079]: info: [ASDebug] Port: 4 Jul 10 17:28:07 volumio volumio[1079]: info: [ASDebug] Inverted: false Jul 10 17:28:07 volumio volumio[1079]: info: [ASDebug] Delay: 720 Jul 10 17:28:09 volumio volumio[1079]: info: [ASDebug] Port: 4 Jul 10 17:28:09 volumio volumio[1079]: info: [ASDebug] Inverted: false Jul 10 17:28:09 volumio volumio[1079]: info: [ASDebug] Delay: 720 Jul 10 17:28:09 volumio volumio[1079]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:28:09 volumio volumio[1079]: Error: EBADF: bad file descriptor, close Jul 10 17:28:09 volumio volumio[1079]: at Object.closeSync (fs.js:439:3) Jul 10 17:28:09 volumio volumio[1079]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Jul 10 17:28:09 volumio volumio[1079]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Jul 10 17:28:09 volumio volumio[1079]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Jul 10 17:28:09 volumio volumio[1079]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Jul 10 17:28:09 volumio volumio[1079]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Jul 10 17:28:09 volumio volumio[1079]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 10 17:28:09 volumio volumio[1079]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jul 10 17:28:09 volumio volumio[1079]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Jul 10 17:28:09 volumio volumio[1079]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Jul 10 17:28:09 volumio volumio[1079]: at Socket.emit (events.js:315:20) Jul 10 17:28:09 volumio volumio[1079]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 10 17:28:09 volumio volumio[1079]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jul 10 17:28:09 volumio volumio[1079]: errno: -9, Jul 10 17:28:09 volumio volumio[1079]: syscall: 'close', Jul 10 17:28:09 volumio volumio[1079]: code: 'EBADF' Jul 10 17:28:09 volumio volumio[1079]: } Jul 10 17:28:09 volumio volumio[1079]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 17:28:09 volumio sudo[4971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-10 17:27 Jul 10 17:28:09 volumio sudo[4971]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 02 Jul 2025 04:07:25 PM CEST" VOLUMIO_VERSION="3.819" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3b9115b1943141a9d7186aa434f8f3c2"