Feb 26 20:26:13 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Feb 26 20:26:15 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 20:26:16 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 26 20:26:21 volumio volumio[1268]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:37 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:37 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:37 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:37 volumio volumio[1268]: info: Feb 26 20:26:37 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 22ms Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand status took 20 milliseconds Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 6ms Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand status took 6 milliseconds Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 26ms Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand status took 24 milliseconds Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:37 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:37 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:37 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:37 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 254ms Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 239ms Feb 26 20:26:37 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 210 milliseconds Feb 26 20:26:37 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:37 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":51182,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:37 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:37 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:37 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:37 volumio volumio[1268]: info: ------------------------------ 328ms Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:37 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:40 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:40 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:40 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:40 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces system playlist update Feb 26 20:26:40 volumio volumio[1268]: info: Ignoring MPD Status Update Feb 26 20:26:40 volumio volumio[1268]: info: Feb 26 20:26:40 volumio volumio[1268]: ---------------------------- MPD announces state update: player Feb 26 20:26:40 volumio volumio[1268]: info: ControllerMpd::getState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand status Feb 26 20:26:40 volumio volumio[1268]: info: ------------------------------ 26ms Feb 26 20:26:40 volumio volumio[1268]: info: sendMpdCommand status took 24 milliseconds Feb 26 20:26:40 volumio volumio[1268]: info: ------------------------------ 18ms Feb 26 20:26:40 volumio volumio[1268]: info: sendMpdCommand status took 17 milliseconds Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:40 volumio volumio[1268]: info: ------------------------------ 15ms Feb 26 20:26:40 volumio volumio[1268]: info: sendMpdCommand status took 14 milliseconds Feb 26 20:26:40 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 20:26:40 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::parseState Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:40 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:40 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:40 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:40 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:40 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bad Omens - Dying To Love","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:40 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:40 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:40 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:40 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:40 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:40 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:26:40 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:40 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:40 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:41 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:41 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:41 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54886,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bad Omens - Dying To Love","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:41 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:41 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:41 volumio volumio[1268]: info: ------------------------------ 274ms Feb 26 20:26:41 volumio volumio[1268]: info: ------------------------------ 266ms Feb 26 20:26:41 volumio volumio[1268]: info: sendMpdCommand playlistinfo took 213 milliseconds Feb 26 20:26:41 volumio volumio[1268]: verbose: ControllerMpd::parseTrackInfo Feb 26 20:26:41 volumio volumio[1268]: info: ControllerMpd::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::servicePushState Feb 26 20:26:41 volumio volumio[1268]: info: CorePlayQueue::getTrack 0 Feb 26 20:26:41 volumio volumio[1268]: verbose: STATE SERVICE {"status":"play","position":0,"seek":54977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bad Omens - Dying To Love","artist":"KINK Distortion","album":null,"uri":"https://22323.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""} Feb 26 20:26:41 volumio volumio[1268]: verbose: CURRENT POSITION 0 Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::syncState stateService play Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::syncState currentStatus play Feb 26 20:26:41 volumio volumio[1268]: info: Received an update from plugin. extracting info from payload Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreStateMachine::pushState Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::volumioPushState Feb 26 20:26:41 volumio volumio[1268]: info: ------------------------------ 359ms Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: SPOTIFY VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: VOLUMIO VOLUME 30 Feb 26 20:26:41 volumio volumio[1268]: SPOTIFY: DELTA VOLUME ENOUGH: false Feb 26 20:26:41 volumio volumio[1268]: info: CALLMETHOD: system_hardware gpio_control saveConfig [object Object] Feb 26 20:26:41 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: gpio_control , saveConfig Feb 26 20:26:42 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Feb 26 20:26:48 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Feb 26 20:26:52 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 26 20:26:52 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 26 20:26:57 volumio volumio[1268]: info: Enabling plugin touch_display Feb 26 20:26:57 volumio volumio[1268]: info: Loading plugin "touch_display"... Feb 26 20:26:58 volumio volumio[1268]: info: PLUGIN START: touch_display Feb 26 20:26:58 volumio volumio[1268]: info: Loading i18n strings for locale nl Feb 26 20:26:58 volumio volumio[1268]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Feb 26 20:26:58 volumio volumio[1268]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:26:58 volumio sudo[1986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 26 20:26:58 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:58 volumio volumio[1268]: info: touch_display: No backlight interface detected. Feb 26 20:26:58 volumio sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 26 20:26:58 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:58 volumio volumio[1268]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 26 20:26:58 volumio sudo[1988]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 26 20:26:58 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:58 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Feb 26 20:26:58 volumio systemd[1]: Stopping getty@tty1.service - Getty on tty1... Feb 26 20:26:58 volumio systemd[1]: getty@tty1.service: Deactivated successfully. Feb 26 20:26:58 volumio systemd[1]: Stopped getty@tty1.service - Getty on tty1. Feb 26 20:26:58 volumio sudo[1990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 26 20:26:58 volumio sudo[1990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:58 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root Feb 26 20:26:58 volumio volumio[1268]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 26 20:26:58 volumio systemd[1]: Reloading. Feb 26 20:26:59 volumio volumio[1268]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 26 20:26:59 volumio sudo[1999]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 26 20:26:59 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:59 volumio sudo[1999]: pam_unix(sudo:session): session closed for user root Feb 26 20:26:59 volumio volumio[1268]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 26 20:26:59 volumio sudo[1997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 26 20:26:59 volumio sudo[1997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:26:59 volumio sudo[1997]: pam_unix(sudo:session): session closed for user root Feb 26 20:26:59 volumio volumio[1268]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 26 20:26:59 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:26:59 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:27:00 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:00 volumio volumio[1268]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 26 20:27:00 volumio systemd[1]: Reloading. Feb 26 20:27:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:27:00 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:27:01 volumio sudo[1990]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: systemctl daemon-reload succeeded. Feb 26 20:27:01 volumio sudo[2057]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 26 20:27:01 volumio sudo[2057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:27:01 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Feb 26 20:27:01 volumio sudo[2057]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: Volumio Kiosk started. Feb 26 20:27:01 volumio startx[2078]: xauth: file /home/volumio/.Xauthority does not exist Feb 26 20:27:01 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Feb 26 20:27:01 volumio volumio[1268]: info: Done. Feb 26 20:27:01 volumio volumio[1268]: info: CoreCommandRouter::volumioGetState Feb 26 20:27:01 volumio startx[2084]: X.Org X Server 1.21.1.7 Feb 26 20:27:01 volumio startx[2084]: X Protocol Version 11, Revision 0 Feb 26 20:27:01 volumio startx[2084]: Current Operating System: Linux volumio 6.12.47-v7l+ #1904 SMP Mon Sep 15 13:08:10 BST 2025 armv7l Feb 26 20:27:01 volumio startx[2084]: Kernel command line: coherent_pool=1M 8250.nr_uarts=1 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_hdmi=0 snd_bcm2835.enable_hdmi=0 video=HDMI-A-1:1024x600M@60 numa=fake=2 system_heap.max_order=0 smsc95xx.macaddr=DC:A6:32:B9:E1:DB vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=7e9efb31-d81e-40e6-b369-17d6be2a2543 imgfile=/volumio_current.sqsh bootpart=UUID=41F4-3F60 datapart=UUID=9fd827c2-5893-40f4-9f75-707203e69ae7 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no fbcon=rotate:0 Feb 26 20:27:01 volumio startx[2084]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Feb 26 20:27:01 volumio startx[2084]: Current version of pixman: 0.44.0 Feb 26 20:27:01 volumio startx[2084]: Before reporting problems, check http://wiki.x.org Feb 26 20:27:01 volumio startx[2084]: to make sure that you have the latest version. Feb 26 20:27:01 volumio startx[2084]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 26 20:27:01 volumio startx[2084]: (++) from command line, (!!) notice, (II) informational, Feb 26 20:27:01 volumio startx[2084]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 26 20:27:01 volumio startx[2084]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Feb 26 20:27:01 2026 Feb 26 20:27:01 volumio startx[2084]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 26 20:27:01 volumio startx[2084]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: X display number found: 0 Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 26 20:27:01 volumio volumio[1268]: info: touch_display: X display number found: 0 Feb 26 20:27:02 volumio kernel: vc4-drm gpu: [drm] User-defined mode not supported: "1024x600": 60 49000 1024 1029 1042 1312 600 602 605 622 0x68 0xa Feb 26 20:27:02 volumio startx[2084]: (EE) Feb 26 20:27:02 volumio startx[2084]: Fatal server error: Feb 26 20:27:02 volumio startx[2084]: (EE) no screens found(EE) Feb 26 20:27:02 volumio startx[2084]: (EE) Feb 26 20:27:02 volumio startx[2084]: Please consult the The X.Org Foundation support Feb 26 20:27:02 volumio startx[2084]: at http://wiki.x.org Feb 26 20:27:02 volumio startx[2084]: for help. Feb 26 20:27:02 volumio startx[2084]: (EE) Please also check the log file at "/var/log/Xorg.0.log" for additional information. Feb 26 20:27:02 volumio startx[2084]: (EE) Feb 26 20:27:02 volumio kernel: vc4-drm gpu: [drm] User-defined mode not supported: "1024x600": 60 49000 1024 1029 1042 1312 600 602 605 622 0x68 0xa Feb 26 20:27:02 volumio startx[2084]: (EE) Server terminated with error (1). Closing log file. Feb 26 20:27:02 volumio volumio[1268]: error: touch_display: Error determining the video outputs: Error: Command failed: /usr/bin/xrandr -display :0 Feb 26 20:27:02 volumio volumio[1268]: Can't open display :0 Feb 26 20:27:16 volumio startx[2083]: xinit: giving up Feb 26 20:27:16 volumio startx[2083]: xinit: unable to connect to X server: Cannot assign requested address Feb 26 20:27:16 volumio startx[2083]: xinit: server error Feb 26 20:27:16 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:27:16 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Feb 26 20:27:21 volumio sudo[2129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 26 20:27:21 volumio sudo[2127]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 26 20:27:21 volumio sudo[2127]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:27:21 volumio sudo[2129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:27:21 volumio sudo[2127]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:21 volumio volumio[1268]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 26 20:27:21 volumio volumio[1268]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:27:21 volumio sudo[2131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 26 20:27:21 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:27:21 volumio volumio[1268]: TypeError: Cannot read properties of undefined (reading 'split') Feb 26 20:27:21 volumio volumio[1268]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Feb 26 20:27:21 volumio volumio[1268]: at ChildProcess.exithandler (node:child_process:413:7) Feb 26 20:27:21 volumio volumio[1268]: at ChildProcess.emit (node:events:514:28) Feb 26 20:27:21 volumio volumio[1268]: at maybeClose (node:internal/child_process:1105:16) Feb 26 20:27:21 volumio volumio[1268]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Feb 26 20:27:21 volumio sudo[2134]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 26 20:27:21 volumio sudo[2134]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 26 20:27:21 volumio volumio[1268]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:27:21 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Feb 26 20:27:21 volumio systemd[1]: Reloading. Feb 26 20:27:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:27:22 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Feb 26 20:27:22 volumio sudo[2129]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:22 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:22 volumio sudo[2134]: pam_unix(sudo:session): session closed for user root Feb 26 20:27:25 volumio sudo[2170]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 20:26' Feb 26 20:27:25 volumio sudo[2170]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"