-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Wed 2026-02-25 11:01:50 CET. -- Feb 25 11:00:39 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:39.792+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:47738 Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.931+01:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.134:47738 @ 0x292ca50" latency=-42.829876ms timeout=10s Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.931+01:00 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.1.134:47738 @ 0x292ca50" latency=-42.874449ms platform=PLATFORM_ANDROID version=5.260120.0 Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.931+01:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 25 11:00:41 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:41 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:41 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.938+01:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" name=Volumio_Quad Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.941+01:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" language=es Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.943+01:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" timezone=Europe/Madrid Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.944+01:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" available=true connected=true macAddress=dc:a6:32:60:88:3b ip4Address=192.168.1.131/24 ip6Address= Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.946+01:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Feb 25 11:00:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:41.946+01:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" setupComplete=true Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:00:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:00:41 volumioquad volumio[817]: amixer -c 0 info | grep "bcm2835 ALSA" Feb 25 11:00:42 volumioquad volumio[817]: amixer -c 1 info | grep "bcm2835 Headphones" Feb 25 11:00:42 volumioquad volumio[817]: Card hw:1 'Headphones'/'bcm2835 Headphones' Feb 25 11:00:42 volumioquad volumio[817]: Invalid card number. Feb 25 11:00:42 volumioquad volumio[817]: amixer -c 5 info | grep "QUAD USB Audio 2.0" Feb 25 11:00:42 volumioquad volumio[817]: Invalid card number. Feb 25 11:00:42 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:42.098+01:00 level=WARN msg="active output device not found in available devices" component=volumio/socket activeDeviceId=5 Feb 25 11:00:42 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:42.098+01:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" selectedOutputId=5 Feb 25 11:00:42 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:42 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:42 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:42 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:42.109+01:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" currentVersion=3.908 latestVersion=3.908 Feb 25 11:00:42 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:42.109+01:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.134:47738 @ 0x292ca50" status=UPDATE_STATUS_NONE progress=0 Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:42 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 11:00:43 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:43.285+01:00 level=INFO msg="authenticated Firebase client" component=volumio/firebase userId=7Wxaw9H4f8ffil3oihdoW7iHWk43 tokenExpiry=2026-02-25T12:00:43.285+01:00 Feb 25 11:00:43 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:43.920+01:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" userId=7Wxaw9H4f8ffil3oihdoW7iHWk43 Feb 25 11:00:43 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:43 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:43 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 11:00:44 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:44.701+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Feb 25 11:00:44 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:44.702+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Feb 25 11:00:44 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:44.702+01:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Feb 25 11:00:44 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:44.702+01:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=spotify error="could not open plugin config file for \"spop\": open /data/configuration/music_service/spop/config.json: no such file or directory" Feb 25 11:00:44 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:44.702+01:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" providers=9 Feb 25 11:00:44 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:44 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:44 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 11:00:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:45.361+01:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" plugins=54 Feb 25 11:00:45 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:45 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:45.365+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_STOPPED positionMs=0 volume=100 Feb 25 11:00:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:45.365+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id=http://mscp3.live-streams.nl:8250/class-flac.flac title="Naim Classical (FLAC)" Feb 25 11:00:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:00:45.474+01:00 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.1.134:47738 @ 0x292ca50" latency=-42.692779ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Feb 25 11:00:46 volumioquad sudo[3148]: volumio : unable to resolve host volumioquad Feb 25 11:00:46 volumioquad sudo[3148]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:00:46 volumioquad sudo[3148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 11:00:46 volumioquad sudo[3148]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:00:46 volumioquad sudo[3148]: pam_unix(sudo:session): session closed for user root Feb 25 11:00:46 volumioquad sudo[3150]: volumio : unable to resolve host volumioquad Feb 25 11:00:46 volumioquad sudo[3150]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:00:46 volumioquad sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 11:00:46 volumioquad sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:00:46 volumioquad sudo[3150]: pam_unix(sudo:session): session closed for user root Feb 25 11:00:46 volumioquad volumio[817]: verbose: New Socket.io Connection to 192.168.1.131 from 192.168.1.134 UA: Mozilla/5.0 (Linux; Android 16; moto g86 5G Build/W1VN36H.60-66; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:46 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 11:00:46 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:46 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:46 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:46 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:46 volumioquad volumio[817]: info: Listing playlists Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 11:00:46 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 11:00:46 volumioquad sudo[3154]: volumio : unable to resolve host volumioquad Feb 25 11:00:46 volumioquad sudo[3154]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:00:46 volumioquad sudo[3156]: volumio : unable to resolve host volumioquad Feb 25 11:00:46 volumioquad sudo[3156]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:00:46 volumioquad sudo[3154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 25 11:00:46 volumioquad sudo[3154]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:00:46 volumioquad sudo[3156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 25 11:00:46 volumioquad sudo[3156]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:00:46 volumioquad sudo[3154]: pam_unix(sudo:session): session closed for user root Feb 25 11:00:46 volumioquad sudo[3156]: pam_unix(sudo:session): session closed for user root Feb 25 11:00:46 volumioquad volumio[817]: verbose: New Socket.io Connection to 192.168.1.131 from 192.168.1.134 UA: Mozilla/5.0 (Linux; Android 16; moto g86 5G Build/W1VN36H.60-66; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/145.0.7632.79 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:47 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 11:00:47 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:47 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:47 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:47 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:47 volumioquad volumio[817]: info: Listing playlists Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 11:00:47 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:48 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:48 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:48 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:48 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 25 11:00:49 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetQueue Feb 25 11:00:49 volumioquad volumio[817]: info: CoreStateMachine::getQueue Feb 25 11:00:49 volumioquad volumio[817]: info: CorePlayQueue::getQueue Feb 25 11:00:52 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 11:00:52 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:00:52 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:00:52 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:00:52 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:00:52 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:00:52 volumioquad volumio[817]: info: CorePlayQueue::getTrack 0 Feb 25 11:00:52 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:00:53 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 25 11:00:53 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 11:00:53 volumioquad volumio[817]: info: Preload queue cleared Feb 25 11:00:54 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 25 11:00:54 volumioquad volumio[817]: info: Preload queue cleared Feb 25 11:00:56 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 11:00:58 volumioquad volumio[817]: info: Preload queue cleared Feb 25 11:00:58 volumioquad volumio[817]: info: CoreCommandRouter::volumioAddQueueItems Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::addQueueItems Feb 25 11:00:58 volumioquad volumio[817]: info: CorePlayQueue::addQueueItems Feb 25 11:00:58 volumioquad volumio[817]: info: Preload queue cleared Feb 25 11:00:58 volumioquad volumio[817]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s9753&filter=s:popular Feb 25 11:00:58 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 25 11:00:58 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushQueue Feb 25 11:00:58 volumioquad volumio[817]: info: CorePlayQueue::saveQueue Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::updateTrackBlock Feb 25 11:00:58 volumioquad volumio[817]: info: CorePlayQueue::getTrackBlock Feb 25 11:00:58 volumioquad volumio[817]: info: CoreCommandRouter::volumioPlay Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::play index 119 Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::stop Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::play index undefined Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 25 11:00:58 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:00:58 volumioquad volumio[817]: info: CoreStateMachine::startPlaybackTimer Feb 25 11:00:58 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:00:58 volumioquad volumio[817]: info: [1772013658695] ControllerWebradio::clearAddPlayTrack Feb 25 11:00:58 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand stop Feb 25 11:00:58 volumioquad volumio[817]: info: sendMpdCommand stop took 10 milliseconds Feb 25 11:00:58 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand clear Feb 25 11:00:58 volumioquad volumio[817]: info: Feb 25 11:00:58 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:00:58 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:00:58 volumioquad volumio[817]: info: sendMpdCommand clear took 2 milliseconds Feb 25 11:00:58 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein" Feb 25 11:00:58 volumioquad volumio[817]: info: Feb 25 11:00:58 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:00:58 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:00:58 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:00:58 volumioquad volumio[817]: info: ------------------------------ 5ms Feb 25 11:00:58 volumioquad volumio[817]: info: Feb 25 11:00:58 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:00:58 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:00:58 volumioquad volumio[817]: error: Upnp client error: Error: This socket has been ended by the other party Feb 25 11:01:00 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein" Feb 25 11:01:00 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:00 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:00 volumioquad volumio[817]: info: ------------------------------ 2226ms Feb 25 11:01:00 volumioquad volumio[817]: info: ------------------------------ 2224ms Feb 25 11:01:00 volumioquad volumio[817]: info: Feb 25 11:01:00 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:01:00 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:01:00 volumioquad volumio[817]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein" took 3 milliseconds Feb 25 11:01:00 volumioquad volumio[817]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 25 11:01:00 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand play Feb 25 11:01:00 volumioquad volumio[817]: info: Feb 25 11:01:00 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:01:00 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:01:00 volumioquad volumio[817]: info: Feb 25 11:01:00 volumioquad volumio[817]: ---------------------------- MPD announces system playlist update Feb 25 11:01:00 volumioquad volumio[817]: info: Ignoring MPD Status Update Feb 25 11:01:00 volumioquad volumio[817]: info: ------------------------------ 6ms Feb 25 11:01:00 volumioquad volumio[817]: info: sendMpdCommand play took 5 milliseconds Feb 25 11:01:00 volumioquad volumio[817]: info: ------------------------------ 5ms Feb 25 11:01:00 volumioquad volumio[817]: info: ------------------------------ 4ms Feb 25 11:01:00 volumioquad systemd[1]: Starting Cleanup of Temporary Directories... Feb 25 11:01:01 volumioquad systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Feb 25 11:01:01 volumioquad systemd[1]: Started Cleanup of Temporary Directories. Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 11 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 11 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: error: MPD returned error for command status: Failed to open "alsa" (alsa); Failed to open ALSA device "volumio": No such device Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 11 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: info: Feb 25 11:01:01 volumioquad volumio[817]: ---------------------------- MPD announces state update: player Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::getState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand status Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand clearerror took 12 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand clearerror took 11 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand clearerror took 11 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 10 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 3 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand status took 5 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseState Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.893+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.894+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.894+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id= title= Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.895+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.895+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id= title= Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 54ms Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 53ms Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 53ms Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 29 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: info: sendMpdCommand playlistinfo took 27 milliseconds Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: verbose: ControllerMpd::parseTrackInfo Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio[817]: info: ControllerMpd::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::servicePushState Feb 25 11:01:01 volumioquad volumio[817]: info: CorePlayQueue::getTrack 119 Feb 25 11:01:01 volumioquad volumio[817]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/crtve/rner3/main/mp3/high?aggregator=tunein","trackType":""} Feb 25 11:01:01 volumioquad volumio[817]: verbose: CURRENT POSITION 119 Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState stateService pause Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::syncState currentStatus stop Feb 25 11:01:01 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:01 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.917+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.918+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id= title= Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.918+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.919+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id= title= Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.919+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" state=STATUS_UNSPECIFIED positionMs=3002 volume=100 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.920+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:47738 @ 0x292ca50" id= title= Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 72ms Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 57ms Feb 25 11:01:01 volumioquad volumio[817]: info: ------------------------------ 57ms Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.949+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:47738 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.949+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:47738 Feb 25 11:01:01 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:01.957+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:50384 Feb 25 11:01:03 volumioquad ntpd[1072]: 178.215.228.24 local addr 192.168.1.131 -> Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 11:01:05 volumioquad volumio[817]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 25 11:01:05 volumioquad volumio[817]: info: Received Get System Version Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 11:01:05 volumioquad volumio[817]: info: Received Get System Info Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 11:01:05 volumioquad volumio[817]: info: Discovery: Getting this device information Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:01:05 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:09 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 11:01:09 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 11:01:11 volumioquad volumio[817]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 25 11:01:11 volumioquad volumio[817]: info: Preparing to save Alsa Options, stopping services first Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:01:11 volumioquad volumio[817]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"QUAD USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:11 volumioquad volumio[817]: warn: Unable to locate the device QUAD USB Audio 2.0 a reboot may be required. Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:11 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 11:01:11 volumioquad volumio[817]: info: Updating Volume Controller Parameters: Device: 5 Name: QUAD USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 11:01:11 volumioquad volumio[817]: info: Disabling external Volume Control Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 11:01:11 volumioquad volumio[817]: info: Preparing to generate the ALSA configuration file Feb 25 11:01:11 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:11 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 11:01:11 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:11 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:11 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:11.773+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:50384 @ 0x2ab0150" state=STATUS_UNSPECIFIED positionMs=13066 volume=100 Feb 25 11:01:11 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:11.773+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:50384 @ 0x2ab0150" id= title= Feb 25 11:01:11 volumioquad volumio[817]: info: Asound.conf file written Feb 25 11:01:11 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:11.790+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:50384 Feb 25 11:01:11 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:11.790+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:50384 Feb 25 11:01:11 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:11.795+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:54814 Feb 25 11:01:11 volumioquad sudo[3276]: volumio : unable to resolve host volumioquad Feb 25 11:01:11 volumioquad sudo[3276]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:11 volumioquad sudo[3276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 25 11:01:11 volumioquad sudo[3276]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:11 volumioquad sudo[3276]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:11 volumioquad volumio[817]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:11 volumioquad volumio[817]: info: Output device has changed, restarting MPD Feb 25 11:01:11 volumioquad sudo[3281]: volumio : unable to resolve host volumioquad Feb 25 11:01:11 volumioquad sudo[3281]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:11 volumioquad volumio[817]: info: Output device has changed, restarting Shairport Sync Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:11 volumioquad sudo[3281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:11 volumioquad sudo[3281]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:11 volumioquad sudo[3281]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:11 volumioquad sudo[3283]: volumio : unable to resolve host volumioquad Feb 25 11:01:11 volumioquad sudo[3283]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:11 volumioquad sudo[3283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:11 volumioquad sudo[3283]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:11 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:11 volumioquad volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 11:01:11 volumioquad volumio[817]: info: Output device has changed, restarting MPD Feb 25 11:01:11 volumioquad volumio[817]: info: Output device has changed, restarting Shairport Sync Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:11 volumioquad sudo[3290]: volumio : unable to resolve host volumioquad Feb 25 11:01:11 volumioquad sudo[3290]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:11 volumioquad sudo[3290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:11 volumioquad sudo[3290]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:11 volumioquad sudo[3290]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:11 volumioquad sudo[3292]: volumio : unable to resolve host volumioquad Feb 25 11:01:11 volumioquad sudo[3292]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:11 volumioquad sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:11 volumioquad sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:11 volumioquad volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 11:01:11 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:11 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:12 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:12 volumioquad volumio[817]: info: Starting Shairport Sync Feb 25 11:01:12 volumioquad volumio[817]: info: Starting Shairport Sync Feb 25 11:01:12 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:12 volumioquad sudo[3308]: volumio : unable to resolve host volumioquad Feb 25 11:01:12 volumioquad sudo[3308]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:12 volumioquad sudo[3308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 11:01:12 volumioquad sudo[3308]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:12 volumioquad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 11:01:12 volumioquad systemd[1]: shairport-sync.service: Succeeded. Feb 25 11:01:12 volumioquad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:12 volumioquad sudo[3310]: volumio : unable to resolve host volumioquad Feb 25 11:01:12 volumioquad sudo[3310]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:12 volumioquad sudo[3310]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 11:01:12 volumioquad sudo[3310]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:12 volumioquad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:12 volumioquad sudo[3308]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:12 volumioquad volumio[817]: info: Shairport-Sync Started Feb 25 11:01:12 volumioquad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 11:01:12 volumioquad sudo[3306]: root : unable to resolve host volumioquad Feb 25 11:01:12 volumioquad sudo[3306]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:12 volumioquad sudo[3306]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:12 volumioquad systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 25 11:01:12 volumioquad systemd[1]: shairport-sync.service: Succeeded. Feb 25 11:01:12 volumioquad sudo[3306]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:12 volumioquad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:12 volumioquad sudo[3306]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:12 volumioquad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:12 volumioquad sudo[3306]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:12 volumioquad sudo[3310]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:12 volumioquad volumio[817]: info: Shairport-Sync Started Feb 25 11:01:12 volumioquad mpd[3317]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:12 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:12 volumioquad sudo[3292]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:12 volumioquad sudo[3283]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:12 volumioquad volumio[817]: error: MPD error: The expression evaluated to a falsy value: Feb 25 11:01:12 volumioquad volumio[817]: assert.ok(self.idling) Feb 25 11:01:12 volumioquad volumio[817]: error: The expression evaluated to a falsy value: Feb 25 11:01:12 volumioquad volumio[817]: assert.ok(self.idling) Feb 25 11:01:12 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:12 volumioquad volumio[817]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Feb 25 11:01:12 volumioquad volumio[817]: info: Preparing to save Alsa Options, stopping services first Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:01:12 volumioquad volumio[817]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"QUAD USB Audio 2.0"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:12 volumioquad volumio[817]: warn: Unable to locate the device QUAD USB Audio 2.0 a reboot may be required. Feb 25 11:01:12 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:13 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 11:01:13 volumioquad volumio[817]: info: Updating Volume Controller Parameters: Device: 5 Name: QUAD USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 11:01:13 volumioquad volumio[817]: info: Disabling external Volume Control Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 11:01:13 volumioquad volumio[817]: info: Preparing to generate the ALSA configuration file Feb 25 11:01:13 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:13 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 11:01:13 volumioquad volumio[817]: info: Asound.conf file unchanged, so no further update is needed Feb 25 11:01:13 volumioquad volumio[817]: info: Output device has changed, restarting MPD Feb 25 11:01:13 volumioquad volumio[817]: info: Output device has changed, restarting Shairport Sync Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad sudo[3341]: volumio : unable to resolve host volumioquad Feb 25 11:01:13 volumioquad sudo[3341]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:13 volumioquad sudo[3341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:13 volumioquad sudo[3341]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:13 volumioquad sudo[3341]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:13 volumioquad sudo[3343]: volumio : unable to resolve host volumioquad Feb 25 11:01:13 volumioquad sudo[3343]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:13 volumioquad sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:13 volumioquad sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:13 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:13 volumioquad volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 11:01:13 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:13 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:13 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:13 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:13 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:13.228+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:54814 @ 0x2bce0c0" state=STATUS_UNSPECIFIED positionMs=14370 volume=100 Feb 25 11:01:13 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:13.228+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:54814 @ 0x2bce0c0" id= title= Feb 25 11:01:13 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:13.237+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:54814 Feb 25 11:01:13 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:13.237+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:54814 Feb 25 11:01:13 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:13.244+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:54828 Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:13 volumioquad volumio[817]: info: Starting Shairport Sync Feb 25 11:01:13 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:13 volumioquad sudo[3355]: volumio : unable to resolve host volumioquad Feb 25 11:01:13 volumioquad sudo[3355]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:13 volumioquad sudo[3355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 11:01:13 volumioquad sudo[3355]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:13 volumioquad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 11:01:13 volumioquad systemd[1]: shairport-sync.service: Succeeded. Feb 25 11:01:13 volumioquad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:13 volumioquad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:13 volumioquad sudo[3355]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:13 volumioquad volumio[817]: info: Shairport-Sync Started Feb 25 11:01:13 volumioquad sudo[3349]: root : unable to resolve host volumioquad Feb 25 11:01:13 volumioquad sudo[3349]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:13 volumioquad sudo[3349]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:13 volumioquad sudo[3349]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:13 volumioquad sudo[3349]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:13 volumioquad sudo[3349]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:13 volumioquad mpd[3362]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:13 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:13 volumioquad sudo[3343]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:13 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 25 11:01:15 volumioquad volumio[817]: info: CoreStateMachine::getcurrentVolume Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 11:01:15 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:15 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:15 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:15 volumioquad volumio[817]: info: CoreStateMachine::updateTrackBlock Feb 25 11:01:15 volumioquad volumio[817]: info: CorePlayQueue::getTrackBlock Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 11:01:15 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:15 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:15 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:15 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Feb 25 11:01:17 volumioquad volumio[817]: info: CoreStateMachine::getcurrentVolume Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 11:01:17 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:17 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:17 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:17 volumioquad volumio[817]: info: CoreStateMachine::updateTrackBlock Feb 25 11:01:17 volumioquad volumio[817]: info: CorePlayQueue::getTrackBlock Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::volumioRetrievevolume Feb 25 11:01:17 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:17.100+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:54828 @ 0x2ab0450" state=STATUS_UNSPECIFIED positionMs=18384 volume=100 Feb 25 11:01:17 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:17.101+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:54828 @ 0x2ab0450" id= title= Feb 25 11:01:17 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:17.104+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:54828 Feb 25 11:01:17 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:17.104+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:54828 Feb 25 11:01:17 volumioquad volumio[817]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:17 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:17.113+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:54836 Feb 25 11:01:17 volumioquad volumio[817]: info: VolumeController:: Volume=undefined Mute =false Feb 25 11:01:17 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:17 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:23 volumioquad volumio[817]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 25 11:01:23 volumioquad sudo[3388]: volumio : unable to resolve host volumioquad Feb 25 11:01:23 volumioquad sudo[3388]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad sudo[3388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:23 volumioquad sudo[3388]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:23 volumioquad sudo[3388]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:23 volumioquad sudo[3390]: volumio : unable to resolve host volumioquad Feb 25 11:01:23 volumioquad sudo[3390]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:24 volumioquad sudo[3390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:24 volumioquad sudo[3390]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:24 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:24 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:24 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:24 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:24 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:24 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:24 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:24 volumioquad sudo[3397]: root : unable to resolve host volumioquad Feb 25 11:01:24 volumioquad sudo[3397]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:24 volumioquad sudo[3397]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:24 volumioquad sudo[3397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:24 volumioquad sudo[3397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:24 volumioquad sudo[3397]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:24 volumioquad mpd[3399]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:24 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:24 volumioquad sudo[3390]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:24 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:28 volumioquad volumio[817]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 25 11:01:28 volumioquad sudo[3403]: volumio : unable to resolve host volumioquad Feb 25 11:01:28 volumioquad sudo[3403]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad sudo[3403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad sudo[3403]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:28 volumioquad sudo[3403]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:28 volumioquad sudo[3405]: volumio : unable to resolve host volumioquad Feb 25 11:01:28 volumioquad sudo[3405]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:28 volumioquad sudo[3405]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:28 volumioquad sudo[3405]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:28 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:28 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:28 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:28 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:28 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:28 volumioquad sudo[3412]: root : unable to resolve host volumioquad Feb 25 11:01:28 volumioquad sudo[3412]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:28 volumioquad sudo[3412]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:28 volumioquad sudo[3412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:28 volumioquad sudo[3412]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:28 volumioquad sudo[3412]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:29 volumioquad mpd[3414]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:29 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:29 volumioquad sudo[3405]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:29 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:29 volumioquad volumio[817]: info: CALLMETHOD: music_service mpd savePlaybackOptions [object Object] Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , savePlaybackOptions Feb 25 11:01:29 volumioquad sudo[3433]: volumio : unable to resolve host volumioquad Feb 25 11:01:29 volumioquad sudo[3433]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad sudo[3433]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad sudo[3433]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:29 volumioquad sudo[3435]: volumio : unable to resolve host volumioquad Feb 25 11:01:29 volumioquad sudo[3435]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:29 volumioquad sudo[3435]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:29 volumioquad sudo[3435]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:29 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:29 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:29 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:29 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:30 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:30 volumioquad sudo[3442]: root : unable to resolve host volumioquad Feb 25 11:01:30 volumioquad sudo[3442]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:30 volumioquad sudo[3442]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:30 volumioquad sudo[3442]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:30 volumioquad sudo[3442]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:30 volumioquad sudo[3442]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:30 volumioquad mpd[3444]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:30 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:30 volumioquad sudo[3435]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:30 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:41 volumioquad volumio[817]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::volumioGetState Feb 25 11:01:41 volumioquad volumio[817]: info: Restoring Previous Volume level: 100 false false Feb 25 11:01:41 volumioquad volumio[817]: info: Output device has changed, restarting MPD Feb 25 11:01:41 volumioquad sudo[3463]: volumio : unable to resolve host volumioquad Feb 25 11:01:41 volumioquad volumio[817]: info: Output device has changed, restarting Shairport Sync Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:41 volumioquad sudo[3463]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:41 volumioquad sudo[3463]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 11:01:41 volumioquad sudo[3463]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:41 volumioquad sudo[3463]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:41 volumioquad sudo[3465]: volumio : unable to resolve host volumioquad Feb 25 11:01:41 volumioquad sudo[3465]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:41 volumioquad sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 11:01:41 volumioquad sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:41 volumioquad systemd[1]: Stopping Music Player Daemon... Feb 25 11:01:41 volumioquad volumio[817]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 11:01:41 volumioquad volumio[817]: info: Volume configurations have been set Feb 25 11:01:41 volumioquad systemd[1]: mpd.service: Succeeded. Feb 25 11:01:41 volumioquad systemd[1]: Stopped Music Player Daemon. Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::volumioUpdateVolumeSettings Feb 25 11:01:41 volumioquad volumio[817]: info: Updating Volume Controller Parameters: Device: 5 Name: QUAD USB Audio 2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 2 Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 25 11:01:41 volumioquad volumio[817]: info: Disabling external Volume Control Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 11:01:41 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:41 volumioquad systemd[1]: Starting Music Player Daemon... Feb 25 11:01:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:41.346+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:54836 @ 0x2ab0c30" state=STATUS_UNSPECIFIED positionMs=42429 volume=100 Feb 25 11:01:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:41.348+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:54836 @ 0x2ab0c30" id= title= Feb 25 11:01:41 volumioquad volumio[817]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Feb 25 11:01:41 volumioquad volumio[817]: info: MPD Permissions set Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 11:01:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:41.377+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:54836 Feb 25 11:01:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:41.377+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:54836 Feb 25 11:01:41 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:41.384+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:36986 Feb 25 11:01:41 volumioquad sudo[3472]: root : unable to resolve host volumioquad Feb 25 11:01:41 volumioquad sudo[3472]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:41 volumioquad sudo[3472]: sudo: unable to resolve host volumioquad: System error Feb 25 11:01:41 volumioquad sudo[3472]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 11:01:41 volumioquad sudo[3472]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 11:01:41 volumioquad sudo[3472]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 11:01:41 volumioquad volumio[817]: Invalid card number. Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Feb 25 11:01:41 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 25 11:01:41 volumioquad volumio[817]: info: Starting Shairport Sync Feb 25 11:01:41 volumioquad sudo[3486]: volumio : unable to resolve host volumioquad Feb 25 11:01:41 volumioquad sudo[3486]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:41 volumioquad sudo[3486]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 11:01:41 volumioquad sudo[3486]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 11:01:41 volumioquad systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 11:01:41 volumioquad systemd[1]: shairport-sync.service: Succeeded. Feb 25 11:01:41 volumioquad systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:41 volumioquad systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 11:01:41 volumioquad sudo[3486]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:41 volumioquad volumio[817]: info: Shairport-Sync Started Feb 25 11:01:42 volumioquad mpd[3482]: Feb 25 11:01 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 11:01:42 volumioquad systemd[1]: Started Music Player Daemon. Feb 25 11:01:42 volumioquad sudo[3465]: pam_unix(sudo:session): session closed for user root Feb 25 11:01:42 volumioquad volumio[817]: error: updateQueue error: null Feb 25 11:01:45 volumioquad volumio[817]: info: VolumeController::SetAlsaVolume100 Feb 25 11:01:45 volumioquad volumio[817]: info: CoreStateMachine::pushState Feb 25 11:01:45 volumioquad volumio[817]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 25 11:01:45 volumioquad volumio[817]: info: CoreCommandRouter::volumioPushState Feb 25 11:01:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:45.718+01:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.134:36986 @ 0x28cef30" state=STATUS_UNSPECIFIED positionMs=47003 volume=100 Feb 25 11:01:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:45.719+01:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.134:36986 @ 0x28cef30" id= title= Feb 25 11:01:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:45.721+01:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.134:36986 Feb 25 11:01:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:45.721+01:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.134:36986 Feb 25 11:01:45 volumioquad volumio5-onboarding[974]: time=2026-02-25T11:01:45.725+01:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.134:37010 Feb 25 11:01:50 volumioquad volumio[817]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Feb 25 11:01:50 volumioquad volumio[817]: info: PLUGIN onReboot : networkfs Feb 25 11:01:50 volumioquad volumio[817]: info: PLUGIN onReboot : audiophonicsonoff Feb 25 11:01:50 volumioquad volumio[817]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 11:01:50 volumioquad volumio[817]: TypeError: Cannot read property 'writeSync' of undefined Feb 25 11:01:50 volumioquad volumio[817]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Feb 25 11:01:50 volumioquad volumio[817]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Feb 25 11:01:50 volumioquad volumio[817]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Feb 25 11:01:50 volumioquad volumio[817]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Feb 25 11:01:50 volumioquad volumio[817]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Feb 25 11:01:50 volumioquad volumio[817]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Feb 25 11:01:50 volumioquad volumio[817]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Feb 25 11:01:50 volumioquad volumio[817]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Feb 25 11:01:50 volumioquad volumio[817]: at Socket.emit (events.js:315:20) Feb 25 11:01:50 volumioquad volumio[817]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Feb 25 11:01:50 volumioquad volumio[817]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Feb 25 11:01:50 volumioquad volumio[817]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 11:01:50 volumioquad sudo[3516]: volumio : unable to resolve host volumioquad Feb 25 11:01:50 volumioquad sudo[3516]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Feb 25 11:01:50 volumioquad sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-25 11:00 Feb 25 11:01:50 volumioquad sudo[3516]: 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="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 05 Feb 2026 01:46:18 PM CET" VOLUMIO_VERSION="3.908" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3e9dbaf8f44792d26e97456a18f12823"