-- Logs begin at Mon 2026-03-23 13:45:31 MSK, end at Tue 2026-03-24 12:33:43 MSK. -- Mar 24 12:32:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:00 volumio volumio[653]: info: CorePlayQueue::getTrack 46 Mar 24 12:32:00 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:32:00 volumio volumio[653]: info: Prefetching next song Mar 24 12:32:05 volumio volumio[653]: info: CoreStateMachine::startPlaybackTimer Mar 24 12:32:05 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:32:05 volumio volumio[653]: info: CoreStateMachine::pushState Mar 24 12:32:05 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:32:05 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 12:32:05 volumio volumio[653]: info: CoreCommandRouter::volumioPushState Mar 24 12:32:05 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 12:32:08 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:32:10 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:19 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:32:21 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" took 4 milliseconds Mar 24 12:32:21 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:32:21 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 15ms Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 11ms Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 9ms Mar 24 12:32:21 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: info: Mar 24 12:32:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:32:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:32:21 volumio volumio[653]: error: Upnp client error: Error: This socket has been ended by the other party Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 17ms Mar 24 12:32:21 volumio volumio[653]: info: sendMpdCommand consume 1 took 12 milliseconds Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 12ms Mar 24 12:32:21 volumio volumio[653]: info: ------------------------------ 11ms Mar 24 12:32:23 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:27 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:32:33 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:39 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:32:43 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:48 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:32:54 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:32:58 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:33:00 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:33:03 volumio volumio[653]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.33 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:03 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.669+03:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.33:60007 Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.700+03:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.33:60007 @ 0x21d0d80" latency=116.054747ms platform=PLATFORM_IOS version=5.260209.0 Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.701+03:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.33:60007 @ 0x21d0d80" latency=96.506832ms timeout=10s Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.701+03:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 12:33:03 volumio volumio[653]: info: Received Get System Info Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:03 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.714+03:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" name=Volumio Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.726+03:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" language=ru Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.733+03:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" timezone=Europe/Moscow Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.737+03:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" available=true connected=true macAddress=b8:27:eb:67:0b:35 ip4Address=192.168.1.34/24 ip6Address= Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.739+03:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" available=false connected=false macAddress= ip4Address= ip6Address= ssid= Mar 24 12:33:03 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:03.741+03:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" setupComplete=true Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 12:33:03 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 24 12:33:03 volumio volumio[653]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 24 12:33:04 volumio volumio[653]: Card hw:1 'Headphones'/'bcm2835 Headphones' Mar 24 12:33:04 volumio volumio[653]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 24 12:33:04 volumio volumio[653]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 24 12:33:04 volumio volumio[653]: Card hw:1 'Headphones'/'bcm2835 Headphones' Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.293+03:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" selectedOutputId=1 Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:04 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:04 volumio volumio[653]: verbose: New Socket.io Connection to 192.168.1.34:3000 from 192.168.1.33 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 24 12:33:04 volumio volumio[653]: info: Received Get System Info Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:04 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.366+03:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" currentVersion=3.912 latestVersion=3.912 Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.367+03:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" status=UPDATE_STATUS_NONE progress=0 Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.369+03:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" userId= Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.370+03:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" providers=3 Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.380+03:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" plugins=0 Mar 24 12:33:04 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.390+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" state=STATUS_STOPPED positionMs=59206 volume=37 Mar 24 12:33:04 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:04.392+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" id="http://localhost:6601/?codec=flac-mp4&transport=raw&key=&url=https%3A%2F%2Fext-strm-ruekt01mts-01.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D0614794c2ce878c2cb59135c892269e501ff6086530bb33b34b82563126f30b5%2Clid%3D1611%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69cfc02e%2F0%2F6881759%2F86e1867a.166047013.21.110280212%2Fflac-mp4&ext=.flac" title="Акации 2" Mar 24 12:33:08 volumio kernel: hwmon hwmon1: Voltage normalised Mar 24 12:33:11 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:11.834+03:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.33:60007 @ 0x21d0d80" latency=94.949434ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 24 12:33:13 volumio kernel: hwmon hwmon1: Undervoltage detected! Mar 24 12:33:13 volumio sudo[9035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 12:33:13 volumio sudo[9035]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 12:33:13 volumio sudo[9035]: pam_unix(sudo:session): session closed for user root Mar 24 12:33:13 volumio sudo[9037]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 12:33:13 volumio sudo[9037]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 12:33:14 volumio sudo[9037]: pam_unix(sudo:session): session closed for user root Mar 24 12:33:14 volumio volumio[653]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 5 Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 12:33:14 volumio sudo[9043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 24 12:33:14 volumio sudo[9043]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 12:33:14 volumio sudo[9043]: pam_unix(sudo:session): session closed for user root Mar 24 12:33:14 volumio sudo[9045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 24 12:33:14 volumio sudo[9045]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 24 12:33:14 volumio sudo[9045]: pam_unix(sudo:session): session closed for user root Mar 24 12:33:14 volumio volumio[653]: verbose: New Socket.io Connection to 192.168.1.34 from 192.168.1.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 12:33:14 volumio volumio[653]: info: Received Get System Info Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:14 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:14 volumio volumio[653]: info: Listing playlists Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 24 12:33:14 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 24 12:33:16 volumio volumio[653]: info: Received Get System Info Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 12:33:16 volumio volumio[653]: info: Discovery: Getting this device information Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::volumioGetState Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 12:33:16 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 24 12:33:17 volumio volumio[653]: info: CoreCommandRouter::volumioPlay Mar 24 12:33:17 volumio volumio[653]: info: CoreStateMachine::play index undefined Mar 24 12:33:17 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 12:33:17 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:17 volumio volumio[653]: info: CoreStateMachine::startPlaybackTimer Mar 24 12:33:17 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:17 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 12:33:17 volumio volumio[653]: info: sendMpdCommand stop took 14 milliseconds Mar 24 12:33:17 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 12:33:17 volumio volumio[653]: info: sendMpdCommand clear took 2 milliseconds Mar 24 12:33:17 volumio volumio[653]: info: Mar 24 12:33:17 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:17 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:17 volumio volumio[653]: info: Mar 24 12:33:17 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:17 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:17 volumio volumio[653]: info: Mar 24 12:33:17 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:17 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:17 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:17 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:17 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:17 volumio volumio[653]: info: ------------------------------ 12ms Mar 24 12:33:17 volumio volumio[653]: info: ------------------------------ 10ms Mar 24 12:33:17 volumio volumio[653]: info: ------------------------------ 8ms Mar 24 12:33:21 volumio volumio[653]: info: CoreCommandRouter::volumioPlay Mar 24 12:33:21 volumio volumio[653]: info: CoreStateMachine::play index undefined Mar 24 12:33:21 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 12:33:21 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:21 volumio volumio[653]: info: CoreStateMachine::startPlaybackTimer Mar 24 12:33:21 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:21 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 12:33:21 volumio volumio[653]: info: sendMpdCommand stop took 10 milliseconds Mar 24 12:33:21 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 12:33:21 volumio volumio[653]: info: sendMpdCommand clear took 2 milliseconds Mar 24 12:33:21 volumio volumio[653]: info: Mar 24 12:33:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:21 volumio volumio[653]: info: Mar 24 12:33:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:21 volumio volumio[653]: info: Mar 24 12:33:21 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:21 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:21 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:21 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:21 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:21 volumio volumio[653]: info: ------------------------------ 11ms Mar 24 12:33:21 volumio volumio[653]: info: ------------------------------ 8ms Mar 24 12:33:21 volumio volumio[653]: info: ------------------------------ 7ms Mar 24 12:33:22 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-157.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dbad9ac4d295399eb3ef264370f110f905491492824e9dd03f8f8f9b5ac0308ef%2Clid%3D57%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-m9-157.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3Dbad9ac4d295399eb3ef264370f110f905491492824e9dd03f8f8f9b5ac0308ef%2Clid%3D57%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" took 5 milliseconds Mar 24 12:33:22 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:22 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 10ms Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 6ms Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 5ms Mar 24 12:33:22 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 12:33:22 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand play Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: Mar 24 12:33:22 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:22 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 10ms Mar 24 12:33:22 volumio volumio[653]: info: sendMpdCommand play took 8 milliseconds Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 6ms Mar 24 12:33:22 volumio volumio[653]: info: ------------------------------ 4ms Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::volumioPlay Mar 24 12:33:23 volumio volumio[653]: info: CoreStateMachine::play index undefined Mar 24 12:33:23 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: info: CoreStateMachine::startPlaybackTimer Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 12:33:23 volumio volumio[653]: info: Mar 24 12:33:23 volumio volumio[653]: ---------------------------- MPD announces state update: player Mar 24 12:33:23 volumio volumio[653]: info: sendMpdCommand stop took 10 milliseconds Mar 24 12:33:23 volumio volumio[653]: info: ControllerMpd::getState Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand status Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand clear Mar 24 12:33:23 volumio volumio[653]: info: Mar 24 12:33:23 volumio volumio[653]: ---------------------------- MPD announces state update: player Mar 24 12:33:23 volumio volumio[653]: info: ControllerMpd::getState Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand status Mar 24 12:33:23 volumio volumio[653]: info: Mar 24 12:33:23 volumio volumio[653]: ---------------------------- MPD announces state update: player Mar 24 12:33:23 volumio volumio[653]: info: ControllerMpd::getState Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand status Mar 24 12:33:23 volumio volumio[653]: info: Mar 24 12:33:23 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:23 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:23 volumio volumio[653]: info: sendMpdCommand status took 16 milliseconds Mar 24 12:33:23 volumio volumio[653]: info: sendMpdCommand clear took 15 milliseconds Mar 24 12:33:23 volumio volumio[653]: info: sendMpdCommand status took 10 milliseconds Mar 24 12:33:23 volumio volumio[653]: info: sendMpdCommand status took 7 milliseconds Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::parseState Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::parseState Mar 24 12:33:23 volumio volumio[653]: verbose: ControllerMpd::parseState Mar 24 12:33:23 volumio volumio[653]: info: ControllerMpd::pushState Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::servicePushState Mar 24 12:33:23 volumio volumio[653]: info: CoreStateMachine::pushState Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::volumioPushState Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Mar 24 12:33:23 volumio volumio[653]: info: ControllerMpd::pushState Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::servicePushState Mar 24 12:33:23 volumio volumio[653]: info: CoreStateMachine::pushState Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: info: CoreCommandRouter::volumioPushState Mar 24 12:33:23 volumio volumio[653]: info: CorePlayQueue::getTrack 47 Mar 24 12:33:23 volumio volumio[653]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current yandex_music Received mpd Mar 24 12:33:24 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:23.998+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" state=STATUS_STOPPED positionMs=0 volume=37 Mar 24 12:33:24 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:23.999+03:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" state=STATUS_STOPPED positionMs=0 volume=37 Mar 24 12:33:24 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:23.999+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" id=yandex_music/track/49830405:225893@genre:rusrock title="Там на горе" Mar 24 12:33:24 volumio volumio5-onboarding[1126]: time=2026-03-24T12:33:24.000+03:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.33:60007 @ 0x21d0d80" id=yandex_music/track/49830405:225893@genre:rusrock title="Там на горе" Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 48ms Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 45ms Mar 24 12:33:24 volumio volumio[653]: info: Mar 24 12:33:24 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:24 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:24 volumio volumio[653]: info: Mar 24 12:33:24 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:24 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:24 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:24 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:24 volumio volumio[653]: error: updateQueue error: null Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 63ms Mar 24 12:33:24 volumio volumio[653]: info: sendMpdCommand playlistinfo took 58 milliseconds Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 10ms Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 8ms Mar 24 12:33:24 volumio volumio[653]: verbose: ControllerMpd::parseTrackInfo Mar 24 12:33:24 volumio volumio[653]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Mar 24 12:33:24 volumio volumio[653]: info: ------------------------------ 84ms Mar 24 12:33:24 volumio volumio[653]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 24 12:33:25 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" took 6 milliseconds Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 14ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 9ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 6ms Mar 24 12:33:25 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 12:33:25 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand play Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 13ms Mar 24 12:33:25 volumio volumio[653]: info: sendMpdCommand play took 10 milliseconds Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 9ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 6ms Mar 24 12:33:25 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: sendMpdCommand addid "http://localhost:6601/?codec=mp3&transport=raw&key=&url=https%3A%2F%2Fstrm-mar-238.strm.yandex.net%2Fmusic-v2%2Fraw%2Fysign1%3D03df3fb2ee1df1ffdb3bfc7efd4f35035b9187727dc6baee56255746d2935f9a%2Clid%3D217%2Cpfx%2Csecret_version%3Dver-1%2Csfx%2Csource%3Dmds%2Cts%3D69d0bf0a%2F0%2F1656428%2F49a150ab.74127157.5.49830405%2F320.mp3&ext=.mp3" took 4 milliseconds Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: verbose: MPD COMMAND [object Object] Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 15ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 10ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 8ms Mar 24 12:33:25 volumio volumio[653]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 24 12:33:25 volumio volumio[653]: verbose: ControllerMpd::sendMpdCommand play Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: Mar 24 12:33:25 volumio volumio[653]: ---------------------------- MPD announces system playlist update Mar 24 12:33:25 volumio volumio[653]: info: Ignoring MPD Status Update Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 14ms Mar 24 12:33:25 volumio volumio[653]: info: sendMpdCommand play took 9 milliseconds Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 8ms Mar 24 12:33:25 volumio volumio[653]: info: ------------------------------ 6ms Mar 24 12:33:26 volumio volumio[653]: info: CoreCommandRouter::volumioGetQueue Mar 24 12:33:26 volumio volumio[653]: info: CoreStateMachine::getQueue Mar 24 12:33:26 volumio volumio[653]: info: CorePlayQueue::getQueue Mar 24 12:33:41 volumio volumio[653]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 12:33:41 volumio volumio[653]: Error: socket hang up Mar 24 12:33:41 volumio volumio[653]: at connResetException (internal/errors.js:607:14) Mar 24 12:33:41 volumio volumio[653]: at TLSSocket.socketOnEnd (_http_client.js:493:23) Mar 24 12:33:41 volumio volumio[653]: at TLSSocket.emit (events.js:327:22) Mar 24 12:33:41 volumio volumio[653]: at endReadableNT (internal/streams/readable.js:1327:12) Mar 24 12:33:41 volumio volumio[653]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Mar 24 12:33:41 volumio volumio[653]: code: 'ECONNRESET' Mar 24 12:33:41 volumio volumio[653]: } Mar 24 12:33:41 volumio volumio[653]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 12:33:43 volumio sudo[9137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-24 12:32 Mar 24 12:33:43 volumio sudo[9137]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"