Aug 30 12:59:00 volumio mpd[8892]: 2026-08-30T12:59:00 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 12:59:00 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 12:59:00 volumio sudo[8884]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:00 volumio sudo[8881]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 30 12:59:00 volumio volumio[1143]: info: CoreStateMachine::getcurrentVolume Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:00 volumio sudo[8868]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:00 volumio sudo[8870]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:00 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:00 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:00 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:00 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:00 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:00 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:00 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:00 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:00 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:00 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:00 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:00 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:00 volumio volumio[1143]: info: CoreStateMachine::updateTrackBlock Aug 30 12:59:00 volumio volumio[1143]: info: CorePlayQueue::getTrackBlock Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:00 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:00.576+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:00 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:00.576+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:00 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:00 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:00 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:00 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:00.640+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:00 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:00.640+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:04 volumio kernel: perf: interrupt took too long (3184 > 3160), lowering kernel.perf_event_max_sample_rate to 62750 Aug 30 12:59:05 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:05 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:21 volumio volumio[1143]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 30 12:59:21 volumio volumio[1143]: info: Preparing to save Alsa Options, stopping services first Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:21 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:21 volumio volumio[1143]: info: Saving Audio Output to: {"output_device":{"value":"0,3","label":"HDMI"}} Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:21 volumio volumio[1143]: info: Found match in Cards Database: setting mixer for card HDMI Aug 30 12:59:21 volumio volumio[1143]: info: Setting mixer Master for card HDMI Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 30 12:59:21 volumio volumio[1143]: info: Updating Volume Controller Parameters: Device: 0,3 Name: HDMI Mixer: Master Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 30 12:59:21 volumio volumio[1143]: info: Disabling external Volume Control Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 12:59:21 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 12:59:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 12:59:22 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:22 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:22.014+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:22 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:22.014+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:22 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:22 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:22 volumio volumio[1143]: info: Asound.conf file written Aug 30 12:59:22 volumio sudo[8973]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 12:59:22 volumio sudo[8973]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio sudo[8973]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio volumio[1143]: No state is present for card PCH Aug 30 12:59:22 volumio volumio[1143]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0269,10250742,00100100 HDA:80862806,80860101,00100000" "0x1025" "0x0742" Aug 30 12:59:22 volumio volumio[1143]: Hardware is initialized using a generic method Aug 30 12:59:22 volumio volumio[1143]: No state is present for card PCH Aug 30 12:59:22 volumio volumio[1143]: info: Output device has changed, restarting MPD Aug 30 12:59:22 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:22 volumio sudo[8982]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:22 volumio sudo[8982]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio sudo[8979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 12:59:22 volumio sudo[8979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 12:59:22 volumio volumio[1143]: info: Output device has changed, restarting MPD Aug 30 12:59:22 volumio sudo[8979]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 12:59:22 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:22 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:22 volumio sudo[8990]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 12:59:22 volumio sudo[8990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio sudo[8990]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:22 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 12:59:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:22 volumio sudo[8994]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:22 volumio sudo[8994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio volumio[1143]: info: MPD Permissions set Aug 30 12:59:22 volumio volumio[1143]: info: MPD Permissions set Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:22 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:22 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:22 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 12:59:22 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:22 volumio sudo[9009]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:22 volumio volumio[1143]: error: [ytcr] Error connecting MPD: Aug 30 12:59:22 volumio volumio[1143]: (Error) read ECONNRESET Aug 30 12:59:22 volumio volumio[1143]: Error: read ECONNRESET Aug 30 12:59:22 volumio volumio[1143]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Aug 30 12:59:22 volumio volumio[1143]: Retrying in 5 seconds... Aug 30 12:59:22 volumio sudo[9009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:22 volumio sudo[9009]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio volumio[1143]: info: Starting Shairport Sync Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:22 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:22 volumio volumio[1143]: info: Starting Shairport Sync Aug 30 12:59:22 volumio sudo[9023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 12:59:22 volumio sudo[9023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:22 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:22 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:22 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 12:59:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:22 volumio systemd[1]: shairport-sync.service: Consumed 1.049s CPU time. Aug 30 12:59:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:22 volumio sudo[9023]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio volumio[1143]: info: Shairport-Sync Started Aug 30 12:59:22 volumio sudo[9026]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 12:59:22 volumio sudo[9026]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:22 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 12:59:22 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 12:59:22 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:22 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:22 volumio sudo[9026]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:22 volumio volumio[1143]: info: Shairport-Sync Started Aug 30 12:59:23 volumio mpd[9021]: 2026-08-30T12:59:23 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 12:59:23 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 12:59:23 volumio sudo[8982]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:23 volumio sudo[8994]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:23 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:23 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:23 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:23 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:23 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:24 volumio sudo[9048]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:24 volumio sudo[9048]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 12:59:24 volumio sudo[9051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:24 volumio sudo[9051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:24 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:24 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:24 volumio sudo[9057]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:24 volumio sudo[9057]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:24 volumio sudo[9057]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:24 volumio sudo[9061]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:24 volumio sudo[9061]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:24 volumio go-librespot[1905]: time="2026-08-30T12:59:24+07:00" level=error msg="did not receive last pong from dealer, 720s passed" Aug 30 12:59:24 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:24 volumio sudo[9064]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:24 volumio sudo[9064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:24 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:24 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:24 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:24 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:24 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:24 volumio sudo[9069]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:24 volumio sudo[9069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:24 volumio sudo[9069]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:25 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 30 12:59:25 volumio volumio[1143]: info: CoreStateMachine::getcurrentVolume Aug 30 12:59:25 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:25 volumio mpd[9071]: 2026-08-30T12:59:25 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 12:59:25 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 12:59:25 volumio sudo[9061]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:25 volumio sudo[9064]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:25 volumio sudo[9048]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:25 volumio sudo[9051]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:25 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:25 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:25 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:25 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:25 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:25 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:25 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:25 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:25 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:25 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:25 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:25 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:25 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:25 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:25 volumio volumio[1143]: info: CoreStateMachine::updateTrackBlock Aug 30 12:59:25 volumio volumio[1143]: info: CorePlayQueue::getTrackBlock Aug 30 12:59:25 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:25 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:25.958+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:25 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:25.958+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:26 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:26 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:26 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:26 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:26.032+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:26 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:26.033+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:26 volumio root[9109]: Cardno: 0 Aug 30 12:59:26 volumio root[9111]: unplug Headphone, unmute Speaker Aug 30 12:59:33 volumio kernel: usb 2-1.2: new high-speed USB device number 16 using ehci-pci Aug 30 12:59:33 volumio kernel: usb 2-1.2: config 1 interface 2 altsetting 0 endpoint 0x5 has an invalid bInterval 0, changing to 7 Aug 30 12:59:33 volumio kernel: usb 2-1.2: New USB device found, idVendor=20b1, idProduct=302e, bcdDevice= 2.01 Aug 30 12:59:33 volumio kernel: usb 2-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Aug 30 12:59:33 volumio kernel: usb 2-1.2: Product: Shanling UA1 Pro Aug 30 12:59:33 volumio kernel: usb 2-1.2: Manufacturer: Shanling UA1 Pro Aug 30 12:59:33 volumio kernel: usb 2-1.2: SerialNumber: Shanling UA1 Pro Aug 30 12:59:33 volumio kernel: hid-generic 0003:20B1:302E.000C: hiddev0,hidraw1: USB HID v1.11 Device [Shanling UA1 Pro Shanling UA1 Pro] on usb-0000:00:1d.0-1.2/input2 Aug 30 12:59:33 volumio mtp-probe[9122]: checking bus 2, device 16: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2" Aug 30 12:59:33 volumio mtp-probe[9122]: bus: 2, device: 16 was not an MTP device Aug 30 12:59:33 volumio (udev-worker)[9125]: controlC5: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 5' failed with exit code 99. Aug 30 12:59:33 volumio volumio[1143]: info: Aug 30 12:59:33 volumio volumio[1143]: ---------------------------- USB Audio Device Attached Aug 30 12:59:33 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Aug 30 12:59:33 volumio mtp-probe[9130]: checking bus 2, device 16: "/sys/devices/pci0000:00/0000:00:1d.0/usb2/2-1/2-1.2" Aug 30 12:59:33 volumio mtp-probe[9130]: bus: 2, device: 16 was not an MTP device Aug 30 12:59:43 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 12:59:44 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 12:59:46 volumio volumio[1143]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Aug 30 12:59:46 volumio volumio[1143]: info: Preparing to save Alsa Options, stopping services first Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:46 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:46 volumio volumio[1143]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"Shanling UA1 Pro"}} Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:46 volumio volumio[1143]: info: Setting mixer PCM for card Shanling UA1 Pro Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioUpdateVolumeSettings Aug 30 12:59:46 volumio volumio[1143]: info: Updating Volume Controller Parameters: Device: 5 Name: Shanling UA1 Pro Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Aug 30 12:59:46 volumio volumio[1143]: info: Disabling external Volume Control Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 12:59:46 volumio volumio[1143]: info: Preparing to generate the ALSA configuration file Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 12:59:46 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:46 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:46 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:46.915+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:46 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:46.915+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:46 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:46 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:46 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:46 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:46 volumio volumio[1143]: info: Asound.conf file written Aug 30 12:59:46 volumio sudo[9202]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 30 12:59:46 volumio sudo[9202]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:46 volumio sudo[9202]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio volumio[1143]: No state is present for card PCH Aug 30 12:59:47 volumio volumio[1143]: Found hardware: "HDA-Intel" "Realtek Generic" "HDA:10ec0269,10250742,00100100 HDA:80862806,80860101,00100000" "0x1025" "0x0742" Aug 30 12:59:47 volumio volumio[1143]: Hardware is initialized using a generic method Aug 30 12:59:47 volumio volumio[1143]: No state is present for card PCH Aug 30 12:59:47 volumio volumio[1143]: No state is present for card Pro Aug 30 12:59:47 volumio volumio[1143]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:302e" "" "" Aug 30 12:59:47 volumio volumio[1143]: Hardware is initialized using a generic method Aug 30 12:59:47 volumio volumio[1143]: No state is present for card Pro Aug 30 12:59:47 volumio volumio[1143]: info: Output device has changed, restarting MPD Aug 30 12:59:47 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:47 volumio sudo[9211]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:47 volumio sudo[9211]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 12:59:47 volumio sudo[9208]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 12:59:47 volumio sudo[9208]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio volumio[1143]: info: Output device has changed, restarting MPD Aug 30 12:59:47 volumio sudo[9208]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 12:59:47 volumio volumio[1143]: info: Output device has changed, restarting Shairport Sync Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:47 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:47 volumio sudo[9221]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:47 volumio sudo[9221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio sudo[9218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 30 12:59:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:47 volumio sudo[9218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:47 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:47 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:47 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:47 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:47 volumio sudo[9218]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio volumio[1143]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 30 12:59:47 volumio volumio[1143]: info: MPD Permissions set Aug 30 12:59:47 volumio volumio[1143]: info: MPD Permissions set Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:47 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Aug 30 12:59:47 volumio sudo[9231]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:47 volumio sudo[9231]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 30 12:59:47 volumio sudo[9231]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio volumio[1143]: info: Starting Shairport Sync Aug 30 12:59:47 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:47 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:47 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:47 volumio volumio[1143]: info: Starting Shairport Sync Aug 30 12:59:47 volumio volumio[1143]: info: mpdhttpout ---Configuration successfully replaced in mpd.conf. Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:47 volumio volumio[1143]: info: CoreCommandRouter::volumioPause Aug 30 12:59:47 volumio volumio[1143]: info: CoreStateMachine::pause Aug 30 12:59:47 volumio volumio[1143]: info: mpdhttpout --- Volumio set on pause Aug 30 12:59:47 volumio sudo[9253]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 12:59:47 volumio sudo[9253]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio sudo[9254]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 30 12:59:47 volumio sudo[9254]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:47 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 30 12:59:47 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Aug 30 12:59:47 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:47 volumio systemd[1]: shairport-sync.service: Consumed 1.009s CPU time. Aug 30 12:59:47 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 30 12:59:47 volumio sudo[9253]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio sudo[9254]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:47 volumio volumio[1143]: info: Shairport-Sync Started Aug 30 12:59:47 volumio volumio[1143]: info: Shairport-Sync Started Aug 30 12:59:48 volumio mpd[9250]: 2026-08-30T12:59:48 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 12:59:48 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 12:59:48 volumio sudo[9221]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:48 volumio sudo[9211]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:48 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:48 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:48 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:48 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:48 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:49 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:49 volumio sudo[9276]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:49 volumio sudo[9276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:49 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 30 12:59:49 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:49 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:49 volumio volumio[1143]: error: [ytcr] Error connecting MPD: Aug 30 12:59:49 volumio volumio[1143]: (Error) read ECONNRESET Aug 30 12:59:49 volumio volumio[1143]: Error: read ECONNRESET Aug 30 12:59:49 volumio volumio[1143]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Aug 30 12:59:49 volumio volumio[1143]: Retrying in 5 seconds... Aug 30 12:59:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:49 volumio sudo[9279]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:49 volumio sudo[9279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:49 volumio sudo[9279]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:49 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:49 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Aug 30 12:59:49 volumio sudo[9284]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:49 volumio sudo[9284]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:49 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:49 volumio sudo[9287]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 30 12:59:49 volumio sudo[9287]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 12:59:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:49 volumio systemd[1]: mpd.service: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 30 12:59:49 volumio systemd[1]: mpd.socket: Deactivated successfully. Aug 30 12:59:49 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 30 12:59:49 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 30 12:59:49 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Aug 30 12:59:49 volumio sudo[9294]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 30 12:59:49 volumio sudo[9294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 30 12:59:49 volumio sudo[9294]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:50 volumio mpd[9296]: 2026-08-30T12:59:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 30 12:59:50 volumio systemd[1]: Started mpd.service - Music Player Daemon. Aug 30 12:59:50 volumio sudo[9276]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:50 volumio sudo[9284]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:50 volumio sudo[9287]: pam_unix(sudo:session): session closed for user root Aug 30 12:59:50 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:50 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:50 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:50 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:50 volumio volumio[1143]: error: MPD error: The expression evaluated to a falsy value: Aug 30 12:59:50 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:50 volumio volumio[1143]: error: The expression evaluated to a falsy value: Aug 30 12:59:50 volumio volumio[1143]: assert.ok(self.idling) Aug 30 12:59:50 volumio volumio[1143]: error: updateQueue error: null Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Aug 30 12:59:50 volumio volumio[1143]: info: CoreStateMachine::getcurrentVolume Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:50 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:50 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:50 volumio volumio[1143]: info: CoreStateMachine::updateTrackBlock Aug 30 12:59:50 volumio volumio[1143]: info: CorePlayQueue::getTrackBlock Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioRetrievevolume Aug 30 12:59:50 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:50.860+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:50 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:50.865+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:50 volumio volumio[1143]: info: VolumeController:: Volume=41 Mute =false Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 12:59:50 volumio volumio[1143]: info: CoreStateMachine::pushState Aug 30 12:59:50 volumio volumio[1143]: info: CoreCommandRouter::volumioPushState Aug 30 12:59:50 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:50.915+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 12:59:50 volumio volumio5-onboarding[1863]: time=2026-08-30T12:59:50.915+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44864 @ 0xc00041c0f0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 12:59:54 volumio go-librespot[1905]: time="2026-08-30T12:59:54+07:00" level=error msg="did not receive last pong from dealer, 750s passed" Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.285+07:00 level=INFO msg="WebSocket connection closed" component=conn/ws remoteAddr=192.168.1.8:44864 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.286+07:00 level=INFO msg="connection to address closed" component=conn/multi addr=192.168.1.8:44864 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.289+07:00 level=INFO msg="established new WebSocket connection" component=conn/ws remoteAddr=192.168.1.8:44696 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.310+07:00 level=INFO msg="check connectivity" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.313+07:00 level=INFO msg="device data request" component=server type=REQUEST_TYPE_DEVICE_DATA peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=456.994769ms timeout=20s Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.313+07:00 level=INFO msg="emitting device capabilities changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" Aug 30 13:00:12 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:12 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.318+07:00 level=INFO msg="emitting device name changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" name=Volumio Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.318+07:00 level=INFO msg="emitting device language changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" language=en Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.322+07:00 level=INFO msg="emitting device timezone changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" timezone=Asia/Jakarta Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.325+07:00 level=INFO msg="emitting ethernet info changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" available=true connected=true macAddress=b8:88:e3:aa:ab:64 ip4Address=192.168.1.6/24 ip6Address= Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.331+07:00 level=INFO msg="emitting wifi info changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" available=true connected=false macAddress= ip4Address= ip6Address= ssid= Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.331+07:00 level=INFO msg="emitting device setup status changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" setupComplete=true Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getExtendedOutputDevices Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 13:00:12 volumio volumio[1143]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 0 -D 0 Aug 30 13:00:12 volumio volumio[1143]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 13:00:12 volumio volumio[1143]: {"cmd":"/usr/local/bin/alsacap -C 0 -D 0","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 0 -D 0\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 13:00:12 volumio volumio[1143]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 30 13:00:12 volumio volumio[1143]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 13:00:12 volumio volumio[1143]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 13:00:12 volumio volumio[1143]: amixer -c 5 info | grep "Shanling UA1 Pro" Aug 30 13:00:12 volumio volumio[1143]: Card sysdefault:5 'Pro'/'Shanling UA1 Pro Shanling UA1 Pro at usb-0000:00:1d.0-1.2, high speed' Aug 30 13:00:12 volumio volumio[1143]: error: Cannot read Audio Device Capabilities Command failed: /usr/local/bin/alsacap -C 5 Aug 30 13:00:12 volumio volumio[1143]: /bin/sh: 1: /usr/local/bin/alsacap: not found Aug 30 13:00:12 volumio volumio[1143]: {"cmd":"/usr/local/bin/alsacap -C 5","code":127,"killed":false,"signal":null,"stack":"Error: Command failed: /usr/local/bin/alsacap -C 5\n/bin/sh: 1: /usr/local/bin/alsacap: not found\n\n at ChildProcess.exithandler (node:child_process:421:12)\n at ChildProcess.emit (node:events:514:28)\n at maybeClose (node:internal/child_process:1105:16)\n at Socket. (node:internal/child_process:457:11)\n at Socket.emit (node:events:514:28)\n at Pipe. (node:net:337:12)"} Aug 30 13:00:12 volumio volumio[1143]: amixer -c 5 info | grep "Shanling UA1 Pro" Aug 30 13:00:12 volumio volumio[1143]: Card sysdefault:5 'Pro'/'Shanling UA1 Pro Shanling UA1 Pro at usb-0000:00:1d.0-1.2, high speed' Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.550+07:00 level=INFO msg="emitting audio outputs changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" selectedOutputId=5 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.557+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://oauth-performer.dfs.volumio.org duration=246.25571ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.568+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://www.googleapis.com duration=255.408886ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.568+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://browsing-performer.dfs.volumio.org duration=256.256973ms Aug 30 13:00:12 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:12 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.579+07:00 level=INFO msg="emitting software info changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" currentVersion=4.119 latestVersion=4.119 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.579+07:00 level=INFO msg="emitting software update progress event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" status=UPDATE_STATUS_NONE progress=0 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.580+07:00 level=INFO msg="emitting user changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" userId= Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.580+07:00 level=INFO msg="emitting music providers changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" providers=3 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.580+07:00 level=INFO msg="emitting plugins changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" plugins=53 Aug 30 13:00:12 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.583+07:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" state=STATUS_PLAYING positionMs=73718 volume=41 Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.584+07:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.1.8:44696 @ 0xc00056a2a0" id="ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Seberapa%20Pantas%22%2C%22artist%22%3A%22Sheila%20On%207%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Fyt3.googleusercontent.com%2Fto7UMeKIDGYq2kwQOt7d2KBmCz0Az8N6jBHcab5-HTP_ZTc_xRrG8FgnPQr5ahgoKr5u0-KIlY7ZijM%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22nSPj50gQ_m4%22%2C%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22playlistSetVideoId%22%3A%227CDA8D211ADEC347%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22playlistId%22%3A%22OLAK5uy_lQHi2ZfTdMH9FBqy36KvH-1eZdXH83lnA%22%2C%22videoId%22%3A%22Mi4t03vxXak%22%7D%7D%7D%7D" title="Seberapa Pantas" Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.794+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://google.com duration=482.190924ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.797+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=http://pushupdates.volumio.org duration=484.86606ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.798+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://functions.volumio.cloud duration=484.674696ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.798+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://functions.volumio.cloud duration=485.504701ms Aug 30 13:00:12 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:12.799+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://securetoken.googleapis.com duration=486.546721ms Aug 30 13:00:13 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:13.051+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://myvolumio.firebaseio.com duration=738.677126ms Aug 30 13:00:13 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:13.134+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=http://plugins.volumio.org duration=822.209982ms Aug 30 13:00:13 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:13.154+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://database.volumio.cloud duration=842.31251ms Aug 30 13:00:13 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:13.433+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=http://cddb.volumio.org duration=1.121205867s Aug 30 13:00:16 volumio sudo[9378]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:00:16 volumio sudo[9378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:16 volumio sudo[9379]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:00:16 volumio sudo[9379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:16 volumio sudo[9379]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:16 volumio sudo[9378]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:16 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.1.6 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 13; 2209116AG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 30 13:00:16 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:16 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:16 volumio volumio[1143]: info: Listing playlists Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Aug 30 13:00:16 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Aug 30 13:00:17 volumio volumio[1143]: info: Received Get System Version Aug 30 13:00:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 30 13:00:17 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:00:18 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:18 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 30 13:00:18 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 30 13:00:19 volumio volumio5-onboarding[1863]: time=2026-08-30T13:00:19.194+07:00 level=INFO msg="endpoint is reachable" component=server type=REQUEST_TYPE_CHECK_INTERNET_CONNECTIVITY peer="192.168.1.8:44696 @ 0xc00056a2a0" latency=457.814543ms timeout=10s endpoint=https://radio-directory.firebaseapp.com duration=6.883511731s Aug 30 13:00:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:00:21 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:21 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:21 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:21 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:00:22 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:22 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 30 13:00:22 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:22 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:22 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:22 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:22 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:24 volumio go-librespot[1905]: time="2026-08-30T13:00:24+07:00" level=error msg="did not receive last pong from dealer, 780s passed" Aug 30 13:00:24 volumio go-librespot[1905]: time="2026-08-30T13:00:24+07:00" level=error msg="did not receive last pong ack from accesspoint, 250s passed" Aug 30 13:00:24 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , openWizard Aug 30 13:00:24 volumio volumio[1143]: info: CoreCommandRouter::Reload Ui Aug 30 13:00:26 volumio sudo[9433]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:00:26 volumio sudo[9433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:26 volumio sudo[9435]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:00:26 volumio sudo[9435]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:26 volumio sudo[9433]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:26 volumio sudo[9435]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:26 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.1.6 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 13; 2209116AG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Aug 30 13:00:26 volumio sudo[9456]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 30 13:00:26 volumio sudo[9456]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:26 volumio sudo[9456]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:26 volumio sudo[9454]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 30 13:00:26 volumio sudo[9454]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 30 13:00:26 volumio sudo[9454]: pam_unix(sudo:session): session closed for user root Aug 30 13:00:26 volumio volumio[1143]: verbose: New Socket.io Connection to 192.168.1.6 from 192.168.1.8 UA: Mozilla/5.0 (Linux; Android 13; 2209116AG Build/TKQ1.221114.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/151.0.7922.199 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 30 13:00:26 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:26 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:26 volumio volumio-remote-updater[760]: Test mode disabled Aug 30 13:00:26 volumio volumio-remote-updater[760]: Alpha mode disabled Aug 30 13:00:26 volumio volumio-remote-updater[760]: Alpha legacy test mode disabled Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:26 volumio volumio[1143]: info: Retrieving Cloud Streaming UI Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:00:26 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:00:27 volumio volumio[1143]: info: Getting Tidal Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Getting Qobuz Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Getting Spotify Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Saving Spotify Acccount Aug 30 13:00:27 volumio volumio[1143]: info: Got Tidal Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Got it Aug 30 13:00:27 volumio volumio[1143]: info: Got it Aug 30 13:00:27 volumio volumio[1143]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 30 13:00:27 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:27 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Retrieving Cloud Streaming UI Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 13:00:27 volumio volumio[1143]: info: Getting Tidal Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Getting Qobuz Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Getting Spotify Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Saving Spotify Acccount Aug 30 13:00:27 volumio volumio[1143]: info: Got Tidal Cloud Configuration Aug 30 13:00:27 volumio volumio[1143]: info: Got it Aug 30 13:00:27 volumio volumio[1143]: info: Got it Aug 30 13:00:27 volumio volumio[1143]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 13:00:27 volumio volumio[1143]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 13:00:27 volumio volumio-remote-updater[760]: Test mode disabled Aug 30 13:00:27 volumio volumio-remote-updater[760]: Alpha mode disabled Aug 30 13:00:27 volumio volumio-remote-updater[760]: Alpha legacy test mode disabled Aug 30 13:00:27 volumio volumio[1143]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: wizard , getWizardSteps Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: my_volumio , showActivationCode Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getAvailableTimezones Aug 30 13:00:27 volumio volumio[1143]: info: Received Get System Info Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 30 13:00:27 volumio volumio[1143]: info: Discovery: Getting this device information Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::volumioGetState Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 30 13:00:27 volumio volumio-remote-updater[760]: Test mode disabled Aug 30 13:00:27 volumio volumio-remote-updater[760]: Alpha mode disabled Aug 30 13:00:27 volumio volumio-remote-updater[760]: Alpha legacy test mode disabled Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 30 13:00:27 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:27 volumio volumio[1143]: info: Retrieving Cloud Streaming UI Aug 30 13:00:28 volumio volumio[1143]: info: Getting Tidal Cloud Configuration Aug 30 13:00:28 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:28 volumio volumio[1143]: info: Getting Qobuz Cloud Configuration Aug 30 13:00:28 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:28 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:28 volumio volumio[1143]: info: Getting Spotify Cloud Configuration Aug 30 13:00:28 volumio volumio[1143]: info: Asking plugin for UI Config Aug 30 13:00:28 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:28 volumio volumio[1143]: info: Saving Spotify Acccount Aug 30 13:00:28 volumio volumio[1143]: info: Got Tidal Cloud Configuration Aug 30 13:00:28 volumio volumio[1143]: info: Got it Aug 30 13:00:28 volumio volumio[1143]: info: Got it Aug 30 13:00:28 volumio volumio[1143]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 30 13:00:28 volumio volumio[1143]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 13:00:28 volumio volumio[1143]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 30 13:00:28 volumio volumio[1143]: info: CoreCommandRouter::executeOnPlugin: appearance , setLanguage Aug 30 13:00:28 volumio volumio[1143]: info: Loading i18n strings for locale en Aug 30 13:00:28 volumio volumio[1143]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:00:28 volumio volumio[1143]: TypeError: Cannot read properties of null (reading 'logger') Aug 30 13:00:28 volumio volumio[1143]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:72:83) Aug 30 13:00:28 volumio volumio[1143]: at ControllerNowPlaying._ControllerNowPlaying_onVolumioLanguageChanged (/data/plugins/user_interface/now_playing/dist/index.js:2086:33) Aug 30 13:00:28 volumio volumio[1143]: at /volumio/node_modules/v-conf/index.js:166:13 Aug 30 13:00:28 volumio volumio[1143]: at /volumio/node_modules/multimap/index.js:140:9 Aug 30 13:00:28 volumio volumio[1143]: at Array.forEach () Aug 30 13:00:28 volumio volumio[1143]: at /volumio/node_modules/multimap/index.js:139:13 Aug 30 13:00:28 volumio volumio[1143]: at mapEach (/volumio/node_modules/multimap/index.js:9:5) Aug 30 13:00:28 volumio volumio[1143]: at Multimap.forEachEntry (/volumio/node_modules/multimap/index.js:133:5) Aug 30 13:00:28 volumio volumio[1143]: at Multimap.forEach (/volumio/node_modules/multimap/index.js:138:10) Aug 30 13:00:28 volumio volumio[1143]: at Config.set (/volumio/node_modules/v-conf/index.js:163:20) Aug 30 13:00:28 volumio volumio[1143]: at volumioAppearance.setLanguage (/volumio/app/plugins/miscellanea/appearance/index.js:326:35) Aug 30 13:00:28 volumio volumio[1143]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Aug 30 13:00:28 volumio volumio[1143]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1685:37) Aug 30 13:00:28 volumio volumio[1143]: at Socket.emit (node:events:514:28) Aug 30 13:00:28 volumio volumio[1143]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 30 13:00:28 volumio volumio[1143]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Aug 30 13:00:28 volumio volumio[1143]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 13:00:28 volumio sudo[9491]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-30 12:59' Aug 30 13:00:28 volumio sudo[9491]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"