Aug 25 21:11:05 volumio autossh[42435]: starting ssh (count 9) Aug 25 21:11:05 volumio autossh[42435]: ssh child pid is 42700 Aug 25 21:11:05 volumio volumiossh-tunnel[42700]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 25 21:11:05 volumio autossh[42435]: ssh exited with error status 255; restarting ssh Aug 25 21:11:23 volumio autossh[42435]: starting ssh (count 10) Aug 25 21:11:23 volumio autossh[42435]: ssh child pid is 42739 Aug 25 21:11:23 volumio volumiossh-tunnel[42739]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 25 21:11:23 volumio autossh[42435]: ssh exited with error status 255; restarting ssh Aug 25 21:11:27 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:27 volumio vtcs[2444]: [feedThread:276] Exiting Aug 25 21:11:27 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:27 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:27 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:27 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:27 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:27 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:27 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:27.541+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:27 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:27.541+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Cowboy Aug 25 21:11:27 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:28 volumio volumio[1123]: info: Unknown command: notifyPlaybackError Aug 25 21:11:28 volumio vtcs[2444]: [2026-08-25 21:11:28.750] [tisoc] [warning] [PlaybackControllerImpl.cpp:201] Illegal state:1 Aug 25 21:11:31 volumio vtcs[2444]: [2026-08-25 21:11:31.069] [tisoc] [warning] [PlaybackControllerImpl.cpp:292] Illegal state:1 Aug 25 21:11:33 volumio vtcs[2444]: [2026-08-25 21:11:33.002] [tisoc] [warning] [PlaybackControllerImpl.cpp:213] Ignore play request because playback state is not STARTED. state_=2, playstate_=1, player_state_=0 Aug 25 21:11:33 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.005+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.005+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.005+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.006+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.006+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.006+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.008+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.008+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:33 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:33 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.172+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.172+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.175+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:33 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:33 volumio vtcs[2444]: [open:50] Opening with sample rate: 44100, type: 0 Aug 25 21:11:33 volumio vtcs[2444]: [start:106] Entering Aug 25 21:11:33 volumio vtcs[2444]: [start:113] Reopening PCM device... Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.767+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.767+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:33 volumio vtcs[2444]: [start:115] PCM opened successfully. Aug 25 21:11:33 volumio vtcs[2444]: [start:120] Exiting Aug 25 21:11:33 volumio vtcs[2444]: [feedThread:223] Entering Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:33 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:33 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.829+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:33 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:33.829+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:33 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:34 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:34 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:34 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:34 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:34 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:34 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:34.254+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=524 volume=100 Aug 25 21:11:34 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:34.254+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:34 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:34 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:34 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:35 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:35 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:35 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:35 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:35 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:35 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:35 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:35 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:35.796+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=75780 volume=100 Aug 25 21:11:35 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:35.796+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:35 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:35 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:36 volumio vtcs[2444]: [2026-08-25 21:11:36.109] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:36 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:36 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.245+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=75780 volume=100 Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.245+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=75780 volume=100 Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.245+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.245+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:36 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:36 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.524+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=76289 volume=100 Aug 25 21:11:36 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:36.524+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=Ghostwriter Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:36 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:41 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:41 volumio vtcs[2444]: [feedThread:276] Exiting Aug 25 21:11:41 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:41 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:41 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.872+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=225176 volume=100 Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.873+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:41 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:41 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:41 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.874+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.874+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.875+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:41 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:41.875+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:41 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:42 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:42 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.032+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.032+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.035+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:42 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:42 volumio vtcs[2444]: [open:50] Opening with sample rate: 44100, type: 0 Aug 25 21:11:42 volumio vtcs[2444]: [start:106] Entering Aug 25 21:11:42 volumio vtcs[2444]: [start:113] Reopening PCM device... Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.348+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.349+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio vtcs[2444]: [start:115] PCM opened successfully. Aug 25 21:11:42 volumio vtcs[2444]: [start:120] Exiting Aug 25 21:11:42 volumio vtcs[2444]: [feedThread:223] Entering Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.423+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.423+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:42 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:42 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.932+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=599 volume=100 Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:42 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:42.933+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:42 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:43 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:43 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:43 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:43 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:43 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:43 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:43.157+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=50308 volume=100 Aug 25 21:11:43 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:43.157+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:43 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:43 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:43 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:44 volumio vtcs[2444]: [2026-08-25 21:11:44.479] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 25 21:11:44 volumio vtcs[2444]: [2026-08-25 21:11:44.479] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_BAD_HEADER Aug 25 21:11:44 volumio vtcs[2444]: [2026-08-25 21:11:44.479] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:44 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:44 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.581+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=50308 volume=100 Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.581+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.581+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=50308 volume=100 Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.581+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:44 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:44 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.924+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=50815 volume=100 Aug 25 21:11:44 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:44.924+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:44 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:45 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:45 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:45 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:45 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:45 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:45 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:45.791+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=110898 volume=100 Aug 25 21:11:45 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:45.791+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:45 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:45 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:45 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:45 volumio vtcs[2444]: [2026-08-25 21:11:45.961] [tisoc] [warning] [flac_decoder.cpp:302] FLACDecoder::error_callback FLAC__STREAM_DECODER_ERROR_STATUS_LOST_SYNC Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:46 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:46 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.004+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=110898 volume=100 Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.004+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=110898 volume=100 Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.004+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.004+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:46 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:46 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.401+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=111491 volume=100 Aug 25 21:11:46 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:46.401+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="Smoke & Mirrors" Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:46 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:52 volumio vtcs[2444]: [feedThread:276] Exiting Aug 25 21:11:52 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:52 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.099+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=116813 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.099+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.111+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.111+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.111+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.111+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:52 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:52 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.521+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.521+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.526+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:52 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:52 volumio vtcs[2444]: [open:50] Opening with sample rate: 44100, type: 0 Aug 25 21:11:52 volumio vtcs[2444]: [start:106] Entering Aug 25 21:11:52 volumio vtcs[2444]: [start:113] Reopening PCM device... Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.800+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.800+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:52 volumio vtcs[2444]: [start:115] PCM opened successfully. Aug 25 21:11:52 volumio vtcs[2444]: [start:120] Exiting Aug 25 21:11:52 volumio vtcs[2444]: [feedThread:223] Entering Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:52 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:52 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.850+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:52 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:52.850+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:52 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:53 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:53 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:53 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:53 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:53 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:53 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:53.319+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=549 volume=100 Aug 25 21:11:53 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:53.320+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title=1976 Aug 25 21:11:53 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:53 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:53 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:54 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:54 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:54 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:54 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:54 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:11:54 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:11:55 volumio autossh[42435]: starting ssh (count 11) Aug 25 21:11:55 volumio autossh[42435]: ssh child pid is 42931 Aug 25 21:11:58 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:58 volumio vtcs[2444]: [feedThread:276] Exiting Aug 25 21:11:58 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:58 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.196+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=67625 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.196+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.199+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.199+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.199+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.199+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:58 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:58 volumio volumio[1123]: info: Pushing metadata Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.414+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.414+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.418+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio vtcs[2444]: [close:85] Entering Aug 25 21:11:58 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:11:58 volumio vtcs[2444]: [open:50] Opening with sample rate: 44100, type: 0 Aug 25 21:11:58 volumio vtcs[2444]: [start:106] Entering Aug 25 21:11:58 volumio vtcs[2444]: [start:113] Reopening PCM device... Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.652+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.652+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:58 volumio vtcs[2444]: [start:115] PCM opened successfully. Aug 25 21:11:58 volumio vtcs[2444]: [start:120] Exiting Aug 25 21:11:58 volumio vtcs[2444]: [feedThread:223] Entering Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:58 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:58 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.724+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=0 volume=100 Aug 25 21:11:58 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:58.725+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:58 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:11:59 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:11:59 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:11:59 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:11:59 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:11:59 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:11:59 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:59.149+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PLAYING positionMs=524 volume=100 Aug 25 21:11:59 volumio volumio5-onboarding[1750]: time=2026-08-25T21:11:59.149+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:11:59 volumio volumio[1123]: info: Display-configuration --- Volumio status=play timeout=120 noifplay=true screensavertype=dpms Aug 25 21:11:59 volumio volumio[1123]: info: Display-configuration --- → Wakeup triggered Aug 25 21:11:59 volumio volumio[1123]: info: Display-configuration --- wakeupScreen: DPMS - screen on Aug 25 21:12:00 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:12:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:12:00 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:12:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:12:00 volumio volumio[1123]: info: Executing endpoint metavolumio Aug 25 21:12:00 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 21:12:04 volumio vtcs[2444]: [pause:147] Entering Aug 25 21:12:04 volumio volumio[1123]: info: CoreCommandRouter::servicePushState Aug 25 21:12:04 volumio volumio[1123]: info: CoreStateMachine::pushState Aug 25 21:12:04 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 21:12:04 volumio volumio[1123]: info: CoreCommandRouter::volumioPushState Aug 25 21:12:04 volumio volumio[1123]: info: Stylish Player: pushState → fmt=s16le rate=44100 isDSD=false Aug 25 21:12:04 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:04.556+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" state=STATUS_PAUSED positionMs=107570 volume=100 Aug 25 21:12:04 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:04.556+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.0.109:33326 @ 0xc00033f7d0" id= title="The Horror" Aug 25 21:12:04 volumio volumio[1123]: info: Display-configuration --- Volumio status=pause timeout=120 noifplay=true screensavertype=dpms Aug 25 21:12:04 volumio vtcs[2444]: [feedThread:276] Exiting Aug 25 21:12:04 volumio vtcs[2444]: [pause:161] Exiting Aug 25 21:12:07 volumio volumiossh-tunnel[42931]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 25 21:12:07 volumio autossh[42435]: ssh exited with error status 255; restarting ssh Aug 25 21:12:19 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:19.980+02:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 21:12:20 volumio volumio[1123]: info: Discovery: Getting this device information Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 21:12:20 volumio volumio[1123]: verbose: New Socket.io Connection to 192.168.0.5:3000 from 192.168.0.109 UA: Dart/3.12 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 25 21:12:20 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.439+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=458.584445ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.443+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=462.72581ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.452+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://www.googleapis.com duration=470.82423ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.578+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://securetoken.googleapis.com duration=597.480356ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.594+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=613.025023ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.637+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=655.917058ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.656+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://functions.volumio.cloud duration=675.358842ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.658+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://functions.volumio.cloud duration=676.553115ms Aug 25 21:12:20 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:20.998+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=http://cddb.volumio.org duration=1.017057372s Aug 25 21:12:21 volumio volumio[1123]: verbose: New Socket.io Connection to 192.168.0.5 from 192.168.0.109 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP4A.251205.006; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.170 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 25 21:12:21 volumio volumio[1123]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 25 21:12:21 volumio volumio[1123]: info: Received Get System Info Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 21:12:21 volumio volumio[1123]: info: Discovery: Getting this device information Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Aug 25 21:12:21 volumio volumio[1123]: info: Listing playlists Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::volumioGetQueue Aug 25 21:12:21 volumio volumio[1123]: info: CoreStateMachine::getQueue Aug 25 21:12:21 volumio volumio[1123]: info: CorePlayQueue::getQueue Aug 25 21:12:21 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:21.116+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=http://pushupdates.volumio.org duration=1.135433698s Aug 25 21:12:21 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:21.118+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://database.volumio.cloud duration=1.136706594s Aug 25 21:12:21 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:21.133+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=https://google.com duration=1.152630173s Aug 25 21:12:21 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 25 21:12:21 volumio volumio5-onboarding[1750]: time=2026-08-25T21:12:21.705+02:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.0.109:33326 @ 0xc00033f7d0" latency=134.787196ms timeout=10s endpoint=http://plugins.volumio.org duration=1.724757966s Aug 25 21:12:22 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 25 21:12:22 volumio volumio[1123]: info: Received Get System Info Aug 25 21:12:22 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 21:12:22 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 21:12:22 volumio volumio[1123]: info: Discovery: Getting this device information Aug 25 21:12:22 volumio volumio[1123]: info: CoreCommandRouter::volumioGetState Aug 25 21:12:22 volumio volumio[1123]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 21:12:26 volumio volumio[1123]: info: CoreCommandRouter::volumioRemoveQueueItem Aug 25 21:12:26 volumio volumio[1123]: info: CoreStateMachine::removeQueueItem Aug 25 21:12:26 volumio volumio[1123]: info: CoreStateMachine::stop Aug 25 21:12:26 volumio volumio[1123]: info: CoreStateMachine::serviceStop Aug 25 21:12:26 volumio volumio[1123]: info: CoreCommandRouter::serviceStop Aug 25 21:12:26 volumio vtcs[2444]: [close:85] Entering Aug 25 21:12:26 volumio vtcs[2444]: [close:100] Exiting Aug 25 21:12:26 volumio volumio[1123]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 21:12:26 volumio volumio[1123]: TypeError: Cannot read properties of undefined (reading 'then') Aug 25 21:12:26 volumio volumio[1123]: at CoreStateMachine.removeQueueItem (/volumio/app/statemachine.js:1372:7) Aug 25 21:12:26 volumio volumio[1123]: at CoreCommandRouter.volumioRemoveQueueItem (/volumio/app/index.js:121:28) Aug 25 21:12:26 volumio volumio[1123]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:173:33) Aug 25 21:12:26 volumio volumio[1123]: at Socket.emit (node:events:514:28) Aug 25 21:12:26 volumio volumio[1123]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 25 21:12:26 volumio volumio[1123]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 25 21:12:26 volumio volumio[1123]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 21:12:26 volumio vtcs[2444]: [2026-08-25 21:12:26] [info] asio async_shutdown error: asio.misc:2 (End of file) Aug 25 21:12:26 volumio sudo[43053]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-25 21:11' Aug 25 21:12:26 volumio sudo[43053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"