-- Logs begin at Thu 2019-02-14 15:41:59 IST, end at Sun 2026-03-29 08:26:23 IST. -- Mar 29 08:25:05 moojik go-librespot[1238]: time="2026-03-29T08:25:05+05:30" level=trace msg="sent dealer ping" Mar 29 08:25:05 moojik go-librespot[1238]: time="2026-03-29T08:25:05+05:30" level=trace msg="received dealer pong" Mar 29 08:25:34 moojik go-librespot[1238]: time="2026-03-29T08:25:34+05:30" level=trace msg="received accesspoint ping" Mar 29 08:25:34 moojik go-librespot[1238]: time="2026-03-29T08:25:34+05:30" level=trace msg="received accesspoint pong ack" Mar 29 08:25:35 moojik go-librespot[1238]: time="2026-03-29T08:25:35+05:30" level=trace msg="sent dealer ping" Mar 29 08:25:35 moojik go-librespot[1238]: time="2026-03-29T08:25:35+05:30" level=trace msg="received dealer pong" Mar 29 08:25:42 moojik volumio[927]: info: Mar 29 08:25:42 moojik volumio[927]: ---------------------------- MPD announces state update: player Mar 29 08:25:42 moojik volumio[927]: info: ControllerMpd::getState Mar 29 08:25:42 moojik volumio[927]: verbose: ControllerMpd::sendMpdCommand status Mar 29 08:25:42 moojik volumio[927]: info: sendMpdCommand status took 1 milliseconds Mar 29 08:25:42 moojik volumio[927]: verbose: ControllerMpd::parseState Mar 29 08:25:42 moojik volumio[927]: info: ControllerMpd::pushState Mar 29 08:25:42 moojik volumio[927]: info: CoreCommandRouter::servicePushState Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::pushState Mar 29 08:25:42 moojik volumio[927]: info: CoreCommandRouter::volumioPushState Mar 29 08:25:42 moojik volumio[927]: info: CorePlayQueue::getTrack 0 Mar 29 08:25:42 moojik volumio[927]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Mar 29 08:25:42 moojik volumio[927]: verbose: CURRENT POSITION 0 Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::syncState stateService stop Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::syncState currentStatus play Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::play index undefined Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::pushState Mar 29 08:25:42 moojik volumio[927]: info: CorePlayQueue::getTrack 0 Mar 29 08:25:42 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 08:25:42 moojik volumio[927]: info: CoreCommandRouter::volumioPushState Mar 29 08:25:42 moojik volumio[927]: info: CorePlayQueue::getTrack 0 Mar 29 08:25:42 moojik volumio[927]: info: ------------------------------ 25ms Mar 29 08:25:42 moojik volumio[927]: info: CoreCommandRouter::volumioGetQueue Mar 29 08:25:42 moojik volumio[927]: info: CoreStateMachine::getQueue Mar 29 08:25:42 moojik volumio[927]: info: CorePlayQueue::getQueue Mar 29 08:25:42 moojik volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 08:25:42 moojik volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 08:25:56 moojik volumio[927]: error: Upnp client error: Error: This socket has been ended by the other party Mar 29 08:25:58 moojik volumio[927]: info: Starting UPNP Playback Mar 29 08:25:58 moojik volumio[927]: info: Preparing playback through UPNP Mar 29 08:25:58 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:25:58 moojik volumio[927]: info: CorePlayQueue::getTrack 0 Mar 29 08:25:58 moojik volumio[927]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 08:25:58 moojik systemd[1]: Starting Cleanup of Temporary Directories... Mar 29 08:25:58 moojik systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Mar 29 08:25:58 moojik systemd[1]: Started Cleanup of Temporary Directories. Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 3ms Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: Mar 29 08:25:58 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:25:58 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:25:58 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:26:05 moojik go-librespot[1238]: time="2026-03-29T08:26:05+05:30" level=trace msg="sent dealer ping" Mar 29 08:26:05 moojik go-librespot[1238]: time="2026-03-29T08:26:05+05:30" level=trace msg="received dealer pong" Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.261+05:30 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.68.108:33368 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.298+05:30 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.68.108:33368 @ 0x34485d0" latency=117.006196ms timeout=10s Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.298+05:30 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.298+05:30 level=INFO msg="app ready event" component=server event=CLIENT_EVENT_TYPE_APP_READY peer="192.168.68.108:33368 @ 0x34485d0" latency=117.892915ms platform=PLATFORM_ANDROID version=5.260209.0 Mar 29 08:26:08 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:08 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.308+05:30 level=INFO msg="emitting device name changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" name=Moojik Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.312+05:30 level=INFO msg="emitting device language changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" language=en Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.316+05:30 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" timezone=Asia/Calcutta Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.318+05:30 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" available=true connected=false macAddress= ip4Address= ip6Address= Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.321+05:30 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" available=true connected=true macAddress=b8:27:eb:67:b0:b8 ip4Address=192.168.68.101/24 ip6Address= ssid=JoaHome Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.322+05:30 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" setupComplete=true Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 29 08:26:08 moojik volumio[927]: amixer -c 0 info | grep "bcm2835 ALSA" Mar 29 08:26:08 moojik volumio[927]: amixer -c 1 info | grep "bcm2835 Headphones" Mar 29 08:26:08 moojik volumio[927]: Card hw:1 'Headphones'/'bcm2835 Headphones' Mar 29 08:26:08 moojik volumio[927]: amixer -c 5 info | grep "SMSL USB AUDIO" Mar 29 08:26:08 moojik volumio[927]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-3f980000.usb-1.1.2, high speed' Mar 29 08:26:08 moojik volumio[927]: amixer -c 5 info | grep "SMSL USB AUDIO" Mar 29 08:26:08 moojik volumio[927]: Card hw:5 'AUDIO'/'SMSL SMSL USB AUDIO at usb-3f980000.usb-1.1.2, high speed' Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.679+05:30 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" selectedOutputId=5 Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:08 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:08 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:08 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.702+05:30 level=INFO msg="emitting software info changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" currentVersion=3.912 latestVersion=3.912 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.703+05:30 level=INFO msg="emitting software update progress event" component=server peer="192.168.68.108:33368 @ 0x34485d0" status=UPDATE_STATUS_NONE progress=0 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.703+05:30 level=INFO msg="emitting user changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" userId=QwkpJlFsS5MrSOPRtqByd546v6t2 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.704+05:30 level=INFO msg="emitting music providers changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" providers=9 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.705+05:30 level=INFO msg="emitting plugins changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" plugins=54 Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.712+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" state=STATUS_STOPPED positionMs= volume=100 Mar 29 08:26:08 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:08.713+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.108:33368 @ 0x34485d0" id="https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEidiODZkNzM1Njg1YjgwNDIzYzgzNDBlOTA2Nzc2N2M0Y182MS5tcDQ/0.flac?token=1774756315~NmZlMzFlYmE1OTllZmQwZjZkOTMxNGNhNGFjMWY5ZTFmMDM2ZWI1NA==" title="Unknown (streaming?)" Mar 29 08:26:08 moojik volumio[927]: verbose: New Socket.io Connection to 192.168.68.101:3000 from 192.168.68.108 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 29 08:26:08 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 29 08:26:09 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:09.325+05:30 level=INFO msg="navigate to next page" component=server type=REQUEST_TYPE_CONTINUE_NAVIGATION peer="192.168.68.108:33368 @ 0x34485d0" latency=126.58833ms timeout=10s from=APP_PAGE_ROOT to=APP_PAGE_LEGACY_DEVICE Mar 29 08:26:09 moojik sudo[24191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 08:26:09 moojik sudo[24191]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 08:26:09 moojik sudo[24191]: pam_unix(sudo:session): session closed for user root Mar 29 08:26:09 moojik sudo[24194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 08:26:09 moojik sudo[24194]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 08:26:09 moojik sudo[24194]: pam_unix(sudo:session): session closed for user root Mar 29 08:26:09 moojik volumio[927]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.108 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Mar 29 08:26:10 moojik sudo[24197]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 29 08:26:10 moojik sudo[24197]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 08:26:10 moojik sudo[24197]: pam_unix(sudo:session): session closed for user root Mar 29 08:26:10 moojik sudo[24199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 29 08:26:10 moojik sudo[24199]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 08:26:10 moojik sudo[24199]: pam_unix(sudo:session): session closed for user root Mar 29 08:26:10 moojik volumio[927]: verbose: New Socket.io Connection to 192.168.68.101 from 192.168.68.108 UA: Mozilla/5.0 (Linux; Android 16; SM-S928B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/146.0.7680.119 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::volumioGetVisibleSources Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 29 08:26:10 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:10 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:10 moojik volumio[927]: info: Listing playlists Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:10 moojik volumio[927]: info: FusionDsp - no preset used Mar 29 08:26:10 moojik volumio[927]: info: FusionDsp - Mar 29 08:26:10 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 29 08:26:11 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 08:26:12 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:12 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:12 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 29 08:26:12 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:12.271+05:30 level=INFO msg="new address was allocated" component=ble/conn old=1 new=2 Mar 29 08:26:12 moojik dbus-daemon[549]: [system] Rejected send message, 1 matched rules; type="error", sender=":1.19" (uid=0 pid=5111 comm="/usr/bin/volumio5-onboarding ") interface="(unset)" member="(unset)" error name="org.freedesktop.DBus.Error.UnknownMethod" requested_reply="0" destination=":1.4" (uid=0 pid=665 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap,hostn") Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: info: Retrieving Cloud Streaming UI Mar 29 08:26:13 moojik volumio[927]: info: Getting Tidal Cloud Configuration Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: info: Getting Qobuz Cloud Configuration Mar 29 08:26:13 moojik volumio[927]: info: Asking plugin for UI Config Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: info: Getting Spotify Cloud Configuration Mar 29 08:26:13 moojik volumio[927]: info: Asking plugin for UI Config Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: info: Saving Spotify Acccount Mar 29 08:26:13 moojik volumio[927]: info: Got Tidal Cloud Configuration Mar 29 08:26:13 moojik volumio[927]: info: Got it Mar 29 08:26:13 moojik volumio[927]: info: Got it Mar 29 08:26:13 moojik volumio[927]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 29 08:26:13 moojik volumio[927]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::volumioGetBrowseSources Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 08:26:13 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 29 08:26:14 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 08:26:14 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:14 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:14 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:14 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:14 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:14 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:14 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:14.611+05:30 level=INFO msg="new address was allocated" component=ble/conn old=2 new=3 Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 08:26:16 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:16 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 29 08:26:16 moojik volumio[927]: info: Received Get System Info Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 29 08:26:16 moojik volumio[927]: info: Discovery: Getting this device information Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:16 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 29 08:26:17 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 08:26:17 moojik volumio[927]: info: Disabling MyMusic plugin upnp Mar 29 08:26:17 moojik sudo[24207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Mar 29 08:26:17 moojik sudo[24207]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 29 08:26:17 moojik volumio[927]: error: Upnp client error: Error: This socket has been ended by the other party Mar 29 08:26:17 moojik systemd[1]: Stopping UPnP Renderer front-end to MPD... Mar 29 08:26:18 moojik volumio[927]: info: Clearing queue after UPNP request Mar 29 08:26:18 moojik volumio[927]: info: CoreStateMachine::ClearQueue Mar 29 08:26:18 moojik volumio[927]: info: CoreStateMachine::stop Mar 29 08:26:18 moojik volumio[927]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 29 08:26:18 moojik volumio[927]: info: CorePlayQueue::clearPlayQueue Mar 29 08:26:18 moojik volumio[927]: info: CorePlayQueue::saveQueue Mar 29 08:26:18 moojik volumio[927]: info: CoreCommandRouter::volumioPushState Mar 29 08:26:18 moojik volumio[927]: info: CoreCommandRouter::volumioPushQueue Mar 29 08:26:18 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:18.761+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" state=STATUS_STOPPED positionMs=0 volume=100 Mar 29 08:26:18 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:18.764+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" id= title= Mar 29 08:26:18 moojik volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 08:26:18 moojik volumio[927]: info: Mar 29 08:26:18 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:18 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:18 moojik volumio[927]: error: updateQueue error: null Mar 29 08:26:18 moojik volumio[927]: info: ------------------------------ 3ms Mar 29 08:26:18 moojik volumio[927]: info: Starting UPNP Playback Mar 29 08:26:18 moojik volumio[927]: info: Preparing playback through UPNP Mar 29 08:26:18 moojik volumio[927]: info: CoreCommandRouter::volumioGetState Mar 29 08:26:18 moojik volumio[927]: info: CorePlayQueue::getTrack 0 Mar 29 08:26:18 moojik volumio[927]: info: CoreStateMachine::setConsumeUpdateService mpd Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 1ms Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 2ms Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces system playlist update Mar 29 08:26:19 moojik volumio[927]: info: Ignoring MPD Status Update Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 1ms Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces state update: player Mar 29 08:26:19 moojik volumio[927]: info: ControllerMpd::getState Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::sendMpdCommand status Mar 29 08:26:19 moojik volumio[927]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 29 08:26:19 moojik volumio[927]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 29 08:26:19 moojik volumio[927]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 29 08:26:19 moojik volumio[927]: info: FusionDsp - else 1 -2.5 Mar 29 08:26:19 moojik volumio[927]: info: Mar 29 08:26:19 moojik volumio[927]: ---------------------------- MPD announces state update: player Mar 29 08:26:19 moojik volumio[927]: info: sendMpdCommand status took 33 milliseconds Mar 29 08:26:19 moojik volumio[927]: info: ControllerMpd::getState Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::sendMpdCommand status Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::parseState Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 08:26:19 moojik volumio[927]: info: sendMpdCommand status took 4 milliseconds Mar 29 08:26:19 moojik volumio[927]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::parseState Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::parseTrackInfo Mar 29 08:26:19 moojik volumio[927]: info: ControllerMpd::pushState Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::servicePushState Mar 29 08:26:19 moojik volumio[927]: verbose: In UPNP mode Mar 29 08:26:19 moojik volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":141,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"94 Kbps","isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NmFmMzNmNjllNDFhMjVjZjk1MDBiOWU0MDhkMTU4ZF82MS5tcDQ/0.flac?token=1774756332~MjVjOWMzNWI1MWI0MGU0ZWY1OTI1MWRmNWI4N2ZmNGI0ZGJhZjhmOQ==","trackType":"tidal"} Mar 29 08:26:19 moojik volumio[927]: verbose: CURRENT POSITION 0 Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::syncState stateService play Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::syncState currentStatus stop Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 43ms Mar 29 08:26:19 moojik volumio[927]: info: sendMpdCommand playlistinfo took 4 milliseconds Mar 29 08:26:19 moojik volumio[927]: verbose: ControllerMpd::parseTrackInfo Mar 29 08:26:19 moojik volumio[927]: info: ControllerMpd::pushState Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::servicePushState Mar 29 08:26:19 moojik volumio[927]: verbose: In UPNP mode Mar 29 08:26:19 moojik volumio[927]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":141,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"477 Kbps","isStreaming":false,"title":"Unknown (streaming?)","artist":"Unknown","album":"Unknown","uri":"https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NmFmMzNmNjllNDFhMjVjZjk1MDBiOWU0MDhkMTU4ZF82MS5tcDQ/0.flac?token=1774756332~MjVjOWMzNWI1MWI0MGU0ZWY1OTI1MWRmNWI4N2ZmNGI0ZGJhZjhmOQ==","trackType":"tidal"} Mar 29 08:26:19 moojik volumio[927]: verbose: CURRENT POSITION 0 Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::syncState stateService play Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::syncState currentStatus play Mar 29 08:26:19 moojik volumio[927]: info: Received an update from plugin. extracting info from payload Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::pushState Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::volumioPushState Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::pushState Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::volumioPushState Mar 29 08:26:19 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:19.680+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 08:26:19 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:19.681+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" id="https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NmFmMzNmNjllNDFhMjVjZjk1MDBiOWU0MDhkMTU4ZF82MS5tcDQ/0.flac?token=1774756332~MjVjOWMzNWI1MWI0MGU0ZWY1OTI1MWRmNWI4N2ZmNGI0ZGJhZjhmOQ==" title="Unknown (streaming?)" Mar 29 08:26:19 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:19.683+05:30 level=INFO msg="emitting player state changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" state=STATUS_PLAYING positionMs=0 volume=100 Mar 29 08:26:19 moojik volumio5-onboarding[5111]: time=2026-03-29T08:26:19.684+05:30 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.68.108:33368,00:00:00:00:00:00%02 @ 0x34485d0" id="https://lgf.audio.tidal.com/mediatracks/CAEaKwgDEic0NmFmMzNmNjllNDFhMjVjZjk1MDBiOWU0MDhkMTU4ZF82MS5tcDQ/0.flac?token=1774756332~MjVjOWMzNWI1MWI0MGU0ZWY1OTI1MWRmNWI4N2ZmNGI0ZGJhZjhmOQ==" title="Unknown (streaming?)" Mar 29 08:26:19 moojik volumio[927]: info: ------------------------------ 63ms Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::volumioGetQueue Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::getQueue Mar 29 08:26:19 moojik volumio[927]: info: CorePlayQueue::getQueue Mar 29 08:26:19 moojik volumio[927]: info: CoreCommandRouter::volumioGetQueue Mar 29 08:26:19 moojik volumio[927]: info: CoreStateMachine::getQueue Mar 29 08:26:19 moojik volumio[927]: info: CorePlayQueue::getQueue Mar 29 08:26:19 moojik volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 08:26:19 moojik volumio[927]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 29 08:26:19 moojik volumio[927]: info: FusionDsp - {"Reload":{"result":"Ok"}} Mar 29 08:26:20 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 29 08:26:21 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 29 08:26:22 moojik volumio[927]: info: Enabling MyMusic plugin upnp Mar 29 08:26:22 moojik volumio[927]: info: Enabling plugin upnp Mar 29 08:26:22 moojik volumio[927]: info: Loading plugin "upnp"... Mar 29 08:26:22 moojik volumio[927]: info: [1774752982952] Starting Upmpd Daemon Mar 29 08:26:22 moojik volumio[927]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 29 08:26:22 moojik volumio[927]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 08:26:22 moojik volumio[927]: Error: listen EADDRINUSE: address already in use :::6599 Mar 29 08:26:22 moojik volumio[927]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Mar 29 08:26:22 moojik volumio[927]: at listenInCluster (net.js:1366:12) Mar 29 08:26:22 moojik volumio[927]: at Server.listen (net.js:1452:7) Mar 29 08:26:22 moojik volumio[927]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Mar 29 08:26:22 moojik volumio[927]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Mar 29 08:26:22 moojik volumio[927]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 29 08:26:22 moojik volumio[927]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 29 08:26:22 moojik volumio[927]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 29 08:26:22 moojik volumio[927]: code: 'EADDRINUSE', Mar 29 08:26:22 moojik volumio[927]: errno: -98, Mar 29 08:26:22 moojik volumio[927]: syscall: 'listen', Mar 29 08:26:22 moojik volumio[927]: address: '::', Mar 29 08:26:22 moojik volumio[927]: port: 6599 Mar 29 08:26:22 moojik volumio[927]: } Mar 29 08:26:22 moojik volumio[927]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 29 08:26:23 moojik sudo[24248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-29 08:25 Mar 29 08:26:23 moojik sudo[24248]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"