Feb 26 20:49:34 volumio volumio[1275]: info: CALLMETHOD: system_controller system setLanguageTimezone [object Object]
Feb 26 20:49:34 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , setLanguageTimezone
Feb 26 20:49:34 volumio volumio[1275]: info: Setting timezone to Europe/Amsterdam
Feb 26 20:49:34 volumio sudo[2449]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/localtime
Feb 26 20:49:34 volumio sudo[2449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:49:34 volumio sudo[2449]: pam_unix(sudo:session): session closed for user root
Feb 26 20:49:34 volumio sudo[2453]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/ln -s /usr/share/zoneinfo/Europe/Amsterdam /etc/localtime
Feb 26 20:49:34 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:49:34 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root
Feb 26 20:49:34 volumio sudo[2457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/localtime
Feb 26 20:49:34 volumio sudo[2457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:49:34 volumio sudo[2457]: pam_unix(sudo:session): session closed for user root
Feb 26 20:49:34 volumio sudo[2461]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-timezone Europe/Amsterdam
Feb 26 20:49:34 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:49:34 volumio dbus-daemon[743]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.121' (uid=0 pid=2462 comm="/usr/bin/timedatectl set-timezone Europe/Amsterdam")
Feb 26 20:49:35 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service...
Feb 26 20:49:35 volumio dbus-daemon[743]: [system] Successfully activated service 'org.freedesktop.timedate1'
Feb 26 20:49:35 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service.
Feb 26 20:49:35 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root
Feb 26 20:49:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage
Feb 26 20:49:35 volumio volumio[1275]: info: Loading i18n strings for locale nl
Feb 26 20:49:35 volumio volumio[1275]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Feb 26 20:49:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone
Feb 26 20:49:35 volumio volumio[1275]: Updating browse sources language
Feb 26 20:49:35 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Feb 26 20:49:35 volumio volumio[1275]: info: Fetching Streaming Services browse cache
Feb 26 20:49:37 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Feb 26 20:50:03 volumio volumio[1275]: info: CoreCommandRouter::volumioPlay
Feb 26 20:50:03 volumio volumio[1275]: info: CoreStateMachine::play index undefined
Feb 26 20:50:03 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService undefined
Feb 26 20:50:03 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:03 volumio volumio[1275]: info: CoreStateMachine::startPlaybackTimer
Feb 26 20:50:03 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:03 volumio volumio[1275]: info: [1772135403795] ControllerWebradio::clearAddPlayTrack
Feb 26 20:50:03 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand stop
Feb 26 20:50:03 volumio volumio[1275]: info: sendMpdCommand stop took 1 milliseconds
Feb 26 20:50:03 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand clear
Feb 26 20:50:03 volumio volumio[1275]: info: sendMpdCommand clear took 1 milliseconds
Feb 26 20:50:03 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand load "https://playerservices.streamtheworld.com/pls/KINK_DISTORTION.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM"
Feb 26 20:50:03 volumio volumio[1275]: info:
Feb 26 20:50:03 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:03 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:03 volumio volumio[1275]: info:
Feb 26 20:50:03 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:03 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:03 volumio volumio[1275]: info:
Feb 26 20:50:03 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:03 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:04 volumio volumio[1275]: info: sendMpdCommand load "https://playerservices.streamtheworld.com/pls/KINK_DISTORTION.pls?DIST=TuneIn&TGT=TuneIn&maxServers=2&gdpr=1&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM" took 359 milliseconds
Feb 26 20:50:04 volumio volumio[1275]: info: CoreStateMachine::setConsumeUpdateService mpd
Feb 26 20:50:04 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand play
Feb 26 20:50:04 volumio volumio[1275]: info:
Feb 26 20:50:04 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:04 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 393ms
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 382ms
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 352ms
Feb 26 20:50:04 volumio volumio[1275]: info:
Feb 26 20:50:04 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:04 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:04 volumio volumio[1275]: info:
Feb 26 20:50:04 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:04 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:04 volumio volumio[1275]: info: sendMpdCommand play took 58 milliseconds
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 57ms
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 28ms
Feb 26 20:50:04 volumio volumio[1275]: info: ------------------------------ 27ms
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 55 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 55 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 38 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 48 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 118 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 104 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 84 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 81 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 82 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 81 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 80ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 46 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 45ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 45 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus stop
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio systemd[1]: systemd-timedated.service: Deactivated successfully.
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 339ms
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 512ms
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 520ms
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 513ms
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , clearUpdateSchedule
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces system playlist update
Feb 26 20:50:05 volumio volumio[1275]: info: Ignoring MPD Status Update
Feb 26 20:50:05 volumio volumio[1275]: info:
Feb 26 20:50:05 volumio volumio[1275]: ---------------------------- MPD announces state update: player
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::getState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand status
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 470ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 451 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 481ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 439 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 440 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 439 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 407 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 408 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 53ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 40 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: info: ------------------------------ 37ms
Feb 26 20:50:05 volumio volumio[1275]: info: sendMpdCommand status took 28 milliseconds
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::parseState
Feb 26 20:50:05 volumio volumio[1275]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:05 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:05 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":885,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:05 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:05 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:05 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 947ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 982ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 931ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 898ms
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:06 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:06 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 791 milliseconds
Feb 26 20:50:06 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 787 milliseconds
Feb 26 20:50:06 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 776 milliseconds
Feb 26 20:50:06 volumio volumio[1275]: info: sendMpdCommand playlistinfo took 773 milliseconds
Feb 26 20:50:06 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:06 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:06 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:06 volumio volumio[1275]: verbose: ControllerMpd::parseTrackInfo
Feb 26 20:50:06 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:06 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:06 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:06 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:06 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:06 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:06 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":977,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:06 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:06 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:06 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:06 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1378,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:06 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:06 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: ControllerMpd::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::servicePushState
Feb 26 20:50:06 volumio volumio[1275]: info: CorePlayQueue::getTrack 0
Feb 26 20:50:06 volumio volumio[1275]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1378,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Bring Me The Horizon - Shadow Moses","artist":"KINK Distortion","album":null,"uri":"https://29023.live.streamtheworld.com:443/KINK_DISTORTION_SC?TGT=TuneIn&DIST=TuneIn&partnertok=eyJhbGciOiJIUzI1NiIsImtpZCI6InR1bmVpbiIsInR5cCI6IkpXVCJ9.eyJ0cnVzdGVkX3BhcnRuZXIiOnRydWUsImlhdCI6MTc3MjEzMzk0NCwiaXNzIjoidGlzcnYifQ.xRX-m9-yIQblV4SUFtPM4et6dPvrEhhFa9cliWEKBGM&gdpr=1","trackType":""}
Feb 26 20:50:06 volumio volumio[1275]: verbose: CURRENT POSITION 0
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState stateService play
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::syncState currentStatus play
Feb 26 20:50:06 volumio volumio[1275]: info: Received an update from plugin. extracting info from payload
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreStateMachine::pushState
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::volumioPushState
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 1700ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 1714ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 1262ms
Feb 26 20:50:06 volumio volumio[1275]: info: ------------------------------ 1255ms
Feb 26 20:50:06 volumio volumio-remote-updater[767]: Test mode disabled
Feb 26 20:50:06 volumio volumio-remote-updater[767]: Alpha mode disabled
Feb 26 20:50:06 volumio volumio-remote-updater[767]: Alpha legacy test mode disabled
Feb 26 20:50:06 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Feb 26 20:50:06 volumio volumio[1275]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display : s reset dpms force on
Feb 26 20:50:06 volumio volumio[1275]: /usr/bin/xset: unable to open display ":"
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: RECEIVED VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: SPOTIFY VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: VOLUMIO VOLUME 30
Feb 26 20:50:07 volumio volumio[1275]: SPOTIFY: DELTA VOLUME ENOUGH: false
Feb 26 20:50:07 volumio volumio[1275]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false}
Feb 26 20:50:07 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Feb 26 20:50:07 volumio volumio[1275]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X'
Feb 26 20:50:23 volumio volumio[1275]: info: CoreCommandRouter::volumioGetState
Feb 26 20:50:26 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Feb 26 20:50:26 volumio volumio[1275]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Feb 26 20:50:52 volumio volumio[1275]: info: Starting Uninstall of plugin user_interface - touch_display
Feb 26 20:50:52 volumio volumio[1275]: info: Uninstalling plugin touch_display
Feb 26 20:50:52 volumio sudo[2719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service
Feb 26 20:50:52 volumio sudo[2721]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service
Feb 26 20:50:52 volumio sudo[2721]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:50:52 volumio sudo[2717]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Feb 26 20:50:52 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:50:52 volumio sudo[2717]: pam_unix(sudo:session): session closed for user root
Feb 26 20:50:52 volumio sudo[2719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:50:52 volumio volumio[1275]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Feb 26 20:50:52 volumio volumio[1275]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 26 20:50:52 volumio sudo[2723]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service
Feb 26 20:50:52 volumio sudo[2723]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Feb 26 20:50:52 volumio volumio[1275]: TypeError: Cannot read properties of undefined (reading 'split')
Feb 26 20:50:52 volumio volumio[1275]: at /data/plugins/user_interface/touch_display/index.js:1298:37
Feb 26 20:50:52 volumio volumio[1275]: at ChildProcess.exithandler (node:child_process:413:7)
Feb 26 20:50:52 volumio volumio[1275]: at ChildProcess.emit (node:events:514:28)
Feb 26 20:50:52 volumio volumio[1275]: at maybeClose (node:internal/child_process:1105:16)
Feb 26 20:50:52 volumio volumio[1275]: at ChildProcess._handle.onexit (node:internal/child_process:305:5)
Feb 26 20:50:52 volumio volumio[1275]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Feb 26 20:50:52 volumio systemd[1]: Reloading.
Feb 26 20:50:53 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:50:53 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:50:53 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Feb 26 20:50:53 volumio sudo[2721]: pam_unix(sudo:session): session closed for user root
Feb 26 20:50:54 volumio systemd[1]: Started getty@tty1.service - Getty on tty1.
Feb 26 20:50:54 volumio sudo[2719]: pam_unix(sudo:session): session closed for user root
Feb 26 20:50:54 volumio sudo[2723]: pam_unix(sudo:session): session closed for user root
Feb 26 20:50:56 volumio sudo[2769]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-26 20:49'
Feb 26 20:50:56 volumio sudo[2769]: 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"