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"