-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Sat 2025-06-14 18:53:26 +07. -- Jun 14 18:52:14 ad1862-x2 volumio[845]: info: Enabling plugin mpd_oled Jun 14 18:52:14 ad1862-x2 volumio[845]: info: Loading plugin "mpd_oled"... Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of OLED_TYPE to Select... Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of NUMBER_OF_BARS to 16 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of GAP_BETWEEN_BARS to 1 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of FRAME_RATE to 50 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of SCROLL_RATE to 8 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of SCROLL_DELAY to 5 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of CLOCK_FORMAT to 24h leading 0 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of PAUSE_SCREEN_TYPE to Play (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of INVERT_DISPLAY_PERIOD to Disabled (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of I2C_ADDRESS to Default for display Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of I2C_BUS to /dev/i2c-1 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of SPI_RESET_GPIO_NUMBER to 25 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of SPI_DC_GPIO_NUMBER to 24 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of SPI_CS to 0 (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Translated default label of DATE_FORMAT to DD-MM-YYYY (default) Jun 14 18:52:14 ad1862-x2 volumio[845]: info: Preparing to generate the ALSA configuration file Jun 14 18:52:14 ad1862-x2 volumio[845]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 14 18:52:14 ad1862-x2 volumio[845]: info: Reading ALSA contributions from plugins. Jun 14 18:52:14 ad1862-x2 volumio[845]: info: Asound.conf file written Jun 14 18:52:14 ad1862-x2 sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 14 18:52:14 ad1862-x2 sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:14 ad1862-x2 sudo[3510]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Output device has changed, restarting MPD Jun 14 18:52:15 ad1862-x2 sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 18:52:15 ad1862-x2 sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Output device has changed, restarting Shairport Sync Jun 14 18:52:15 ad1862-x2 sudo[3516]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:15 ad1862-x2 sudo[3519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 18:52:15 ad1862-x2 sudo[3519]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 systemd[1]: Stopping Music Player Daemon... Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 18:52:15 ad1862-x2 volumio[845]: info: PLUGIN START: mpd_oled Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 14 18:52:15 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Preparing to generate the ALSA configuration file Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Done. Jun 14 18:52:15 ad1862-x2 volumio[845]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Reading ALSA contributions from plugins. Jun 14 18:52:15 ad1862-x2 volumio[845]: info: MPD Permissions set Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Asound.conf file unchanged, so no further update is needed Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Output device has changed, restarting MPD Jun 14 18:52:15 ad1862-x2 sudo[3527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 18:52:15 ad1862-x2 sudo[3527]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 sudo[3527]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Output device has changed, restarting Shairport Sync Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:15 ad1862-x2 sudo[3530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 18:52:15 ad1862-x2 sudo[3530]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 18:52:15 ad1862-x2 volumio[845]: info: MPD Permissions set Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Starting Shairport Sync Jun 14 18:52:15 ad1862-x2 volumio[845]: info: Starting Shairport Sync Jun 14 18:52:15 ad1862-x2 sudo[3544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 18:52:15 ad1862-x2 sudo[3544]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 sudo[3547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 18:52:15 ad1862-x2 sudo[3547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:15 ad1862-x2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Enabling plugin mpd_oled Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Loading plugin "mpd_oled"... Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Applying required configuration parameters for plugin mpd_oled Jun 14 18:52:16 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Preparing to generate the ALSA configuration file Jun 14 18:52:16 ad1862-x2 volumio[845]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Reading ALSA contributions from plugins. Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Asound.conf file unchanged, so no further update is needed Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Output device has changed, restarting MPD Jun 14 18:52:16 ad1862-x2 systemd[1]: shairport-sync.service: Succeeded. Jun 14 18:52:16 ad1862-x2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:16 ad1862-x2 sudo[3550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 18:52:16 ad1862-x2 sudo[3550]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:16 ad1862-x2 sudo[3550]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Output device has changed, restarting Shairport Sync Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:16 ad1862-x2 sudo[3554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 18:52:16 ad1862-x2 sudo[3554]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:16 ad1862-x2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:16 ad1862-x2 sudo[3544]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:16 ad1862-x2 sudo[3547]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 18:52:16 ad1862-x2 volumio[845]: info: PLUGIN START: mpd_oled Jun 14 18:52:16 ad1862-x2 volumio[845]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Jun 14 18:52:16 ad1862-x2 volumio[845]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Jun 14 18:52:16 ad1862-x2 volumio[845]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 14 18:52:16 ad1862-x2 volumio[845]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Preparing to generate the ALSA configuration file Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Done. Jun 14 18:52:16 ad1862-x2 volumio[845]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Reading ALSA contributions from plugins. Jun 14 18:52:16 ad1862-x2 volumio[845]: info: MPD Permissions set Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Shairport-Sync Started Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Shairport-Sync Started Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Starting Shairport Sync Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Asound.conf file unchanged, so no further update is needed Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Output device has changed, restarting MPD Jun 14 18:52:16 ad1862-x2 sudo[3568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 18:52:16 ad1862-x2 sudo[3568]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:16 ad1862-x2 sudo[3570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 14 18:52:16 ad1862-x2 sudo[3570]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:16 ad1862-x2 sudo[3570]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Output device has changed, restarting Shairport Sync Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:16 ad1862-x2 sudo[3568]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:16 ad1862-x2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 14 18:52:16 ad1862-x2 systemd[1]: shairport-sync.service: Succeeded. Jun 14 18:52:16 ad1862-x2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:16 ad1862-x2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:16 ad1862-x2 sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 14 18:52:16 ad1862-x2 sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:16 ad1862-x2 volumio[845]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 14 18:52:17 ad1862-x2 volumio[845]: info: MPD Permissions set Jun 14 18:52:17 ad1862-x2 volumio[845]: info: Shairport-Sync Started Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 14 18:52:17 ad1862-x2 volumio[845]: info: Starting Shairport Sync Jun 14 18:52:17 ad1862-x2 sudo[3587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 14 18:52:17 ad1862-x2 sudo[3587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:17 ad1862-x2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 14 18:52:17 ad1862-x2 systemd[1]: shairport-sync.service: Succeeded. Jun 14 18:52:17 ad1862-x2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:17 ad1862-x2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 14 18:52:17 ad1862-x2 sudo[3587]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:17 ad1862-x2 volumio[845]: info: Shairport-Sync Started Jun 14 18:52:19 ad1862-x2 systemd[1]: mpd.service: Succeeded. Jun 14 18:52:19 ad1862-x2 systemd[1]: Stopped Music Player Daemon. Jun 14 18:52:19 ad1862-x2 systemd[1]: Starting Music Player Daemon... Jun 14 18:52:19 ad1862-x2 sudo[3606]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 14 18:52:19 ad1862-x2 sudo[3606]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 14 18:52:19 ad1862-x2 sudo[3606]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:20 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetState Jun 14 18:52:20 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPlay Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CoreStateMachine::play index undefined Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CoreStateMachine::startPlaybackTimer Jun 14 18:52:22 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:22 ad1862-x2 volumio[845]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22November%20Rain%22%2C%22artist%22%3A%22Guns%20N'%20Roses%22%2C%22album%22%3A%22Use%20Your%20Illusion%20I%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FpcrIVPiugsSpXJJPCaPNdp2vpHPo5UsmsoVjqlT7L1p7Lki663u9tONk-S3oqK9GvAsoj2m0AaNgNJj9Zg%3Dw701-h701-s-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%22y6lfK3bH4z8%22%2C%22playlistId%22%3A%22RDAMVMy6lfK3bH4z8%22%2C%22params%22%3A%22wAEB%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%22videoId%22%3A%22y6lfK3bH4z8%22%2C%22playlistId%22%3A%22RDAMVMy6lfK3bH4z8%22%2C%22params%22%3A%22wAEB%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Jun 14 18:52:22 ad1862-x2 mpd[3610]: Jun 14 18:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 14 18:52:22 ad1862-x2 systemd[1]: Started Music Player Daemon. Jun 14 18:52:22 ad1862-x2 sudo[3530]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:22 ad1862-x2 sudo[3554]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:22 ad1862-x2 sudo[3519]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:22 ad1862-x2 sudo[3574]: pam_unix(sudo:session): session closed for user root Jun 14 18:52:22 ad1862-x2 volumio[845]: error: MPD error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: MPD error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: MPD error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: The expression evaluated to a falsy value: Jun 14 18:52:22 ad1862-x2 volumio[845]: assert.ok(self.idling) Jun 14 18:52:22 ad1862-x2 volumio[845]: error: updateQueue error: null Jun 14 18:52:24 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:24 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand stop Jun 14 18:52:24 ad1862-x2 volumio[845]: info: sendMpdCommand stop took 2 milliseconds Jun 14 18:52:24 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand clear Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: sendMpdCommand clear took 3 milliseconds Jun 14 18:52:24 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand addid "https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube" Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: error: updateQueue error: null Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 10ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: sendMpdCommand addid "https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube" took 8 milliseconds Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 8ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 7ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 5ms Jun 14 18:52:24 ad1862-x2 volumio[845]: verbose: MPD COMMAND [object Object] Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 12ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 7ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 6ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 5ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 14 18:52:24 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand play Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Jun 14 18:52:24 ad1862-x2 volumio[845]: ---------------------------- MPD announces system playlist update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: Ignoring MPD Status Update Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 11ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: sendMpdCommand play took 8 milliseconds Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 8ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 6ms Jun 14 18:52:24 ad1862-x2 volumio[845]: info: ------------------------------ 21ms Jun 14 18:52:25 ad1862-x2 volumio[845]: info: Jun 14 18:52:25 ad1862-x2 volumio[845]: ---------------------------- MPD announces state update: player Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::getState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:52:25 ad1862-x2 volumio[845]: info: Jun 14 18:52:25 ad1862-x2 volumio[845]: ---------------------------- MPD announces state update: player Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::getState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:52:25 ad1862-x2 volumio[845]: info: Jun 14 18:52:25 ad1862-x2 volumio[845]: ---------------------------- MPD announces state update: player Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::getState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:52:25 ad1862-x2 volumio[845]: info: Jun 14 18:52:25 ad1862-x2 volumio[845]: ---------------------------- MPD announces state update: player Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::getState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand status Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand status took 30 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand status took 39 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand status took 39 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand status took 38 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand clearerror took 14 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseState Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseTrackInfo Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::servicePushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain","artist":"Guns N' Roses","album":"Use Your Illusion I","uri":"https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: CURRENT POSITION 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState stateService stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: No code Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ------------------------------ 103ms Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand clearerror took 76 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand clearerror took 75 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand clearerror took 74 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand playlistinfo took 65 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand playlistinfo took 65 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: info: sendMpdCommand playlistinfo took 64 milliseconds Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: ControllerMpd::parseTrackInfo Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::servicePushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain","artist":"Guns N' Roses","album":"Use Your Illusion I","uri":"https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: CURRENT POSITION 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState stateService stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: No code Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::servicePushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain","artist":"Guns N' Roses","album":"Use Your Illusion I","uri":"https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: CURRENT POSITION 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState stateService stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: No code Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ControllerMpd::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::servicePushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CorePlayQueue::getTrack 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"November Rain","artist":"Guns N' Roses","album":"Use Your Illusion I","uri":"https://rr6---sn-5fo-c336.googlevideo.com/videoplayback?expire=1749923543&ei=d2JNaP-oLvTd3LUP2_OO0AQ&ip=182.232.139.187&id=o-AH8UKWowZ9QyEoOBN7l9aLWot9662Xn993zJIx3it-x-&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&met=1749901943%2C&mh=U9&mm=31%2C26&mn=sn-5fo-c336%2Csn-npoe7ne7&ms=au%2Conr&mv=m&mvi=6&pl=22&rms=au%2Cau&pcm2=yes&gcr=th&initcwndbps=377500&bui=AY1jyLOSQBlV19Hvf_OaHBUdvdkLMBL9ItE8l1DjqLDmJ20ZmltAwolIGle03HENBxZxIs5TKlwKC_xZ&spc=l3OVKbk_3bw_PAZV4HeHAN-4aDiwxAa8vfHz5KzF1XWX48rD4D--NpaTczKRRM4ZS9o&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=DKMRDFk2v2SyEz2UN9VqnCYQ&rqh=1&gir=yes&clen=8766026&dur=536.081&lmt=1714915174709797&mt=1749901738&fvip=3&keepalive=yes&fexp=51466643&c=WEB_REMIX&sefc=1&txp=5532434&n=AaAt6o540KgmZ4B5uH-_w8_eY7tfIJ1yETAEKnoD&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cpcm2%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=met%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRQIhAPM5ICPaKj7UZtNB5CVM6-yel6jUDzPg7L2NBuspv7l-AiAM1IBO_rOH_S1DVe6ILVu4WZQeRZO4K_07nw5bGfhm-A%3D%3D&pot=MniX0BSxfiuCwZfsBLjkb7dKkCAq8T47L61_mBiZeYJmmtDiARsSDBCT9CUD7_wgjkGC_qpGc65J5BfbSRCY8H-IZg_qhFVkK58yHzsfoc6iQv99SHo61k5bEI5wb47I5ADyVRKTKpMEM8hIS8MMJTGt0Lf2LDRvSeI%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Jun 14 18:52:25 ad1862-x2 volumio[845]: verbose: CURRENT POSITION 4 Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState stateService stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::syncState currentStatus stop Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: No code Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreStateMachine::pushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioPushState Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ------------------------------ 205ms Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ------------------------------ 205ms Jun 14 18:52:25 ad1862-x2 volumio[845]: info: ------------------------------ 204ms Jun 14 18:52:26 ad1862-x2 volumiologrotate[515]: ls: cannot access '/var/log/samba/log.wb-AD1862': No such file or directory Jun 14 18:52:26 ad1862-x2 volumiologrotate[515]: ls: cannot access 'X2': No such file or directory Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Retrieving Cloud Streaming UI Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Getting Tidal Cloud Configuration Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Getting Qobuz Cloud Configuration Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Asking plugin for UI Config Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Getting Spotify Cloud Configuration Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Asking plugin for UI Config Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Saving Spotify Acccount Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Got Tidal Cloud Configuration Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Got it Jun 14 18:52:28 ad1862-x2 volumio[845]: info: Got it Jun 14 18:52:28 ad1862-x2 volumio[845]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 14 18:52:28 ad1862-x2 volumio[845]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetBrowseSources Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jun 14 18:52:28 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jun 14 18:52:32 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetState Jun 14 18:52:38 ad1862-x2 volumio[845]: info: CoreCommandRouter::volumioGetState Jun 14 18:52:47 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:52:47 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:52:50 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:52:50 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:52:55 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:52:56 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:52:57 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:52:57 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:52:57 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:52:59 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:52:59 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:52:59 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:01 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:01 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:53:04 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:04 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:04 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:05 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:05 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:05 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:05 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:05 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:05 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:13 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:13 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:53:14 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:14 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:14 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:14 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:14 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:14 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:16 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:16 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:16 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:16 ad1862-x2 volumio[845]: error: Error browsing0/error_message:Error: Did not get expected response from server:{"Envelope":{"$":{"s:encodingStyle":"http://schemas.xmlsoap.org/soap/encoding/","xmlns:s":"http://schemas.xmlsoap.org/soap/envelope/"},"Body":[{"Fault":[{"faultcode":[{"_":"s:Client"}],"faultstring":[{"_":"UPnPError"}],"detail":[{"UPnPError":[{"$":{"xmlns":"urn:schemas-upnp-org:control-1-0"},"errorCode":[{"_":"720"}],"errorDescription":[{"_":"Cannot process the request: Not a valid folder"}]}]}]}]}]}} Jun 14 18:53:16 ad1862-x2 volumio[845]: error: Failed to execute browseSource: Jun 14 18:53:20 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:20 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:53:22 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:23 ad1862-x2 volumio[845]: info: Preload queue cleared Jun 14 18:53:24 ad1862-x2 volumio[845]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 14 18:53:24 ad1862-x2 volumio[845]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 18:53:24 ad1862-x2 volumio[845]: TypeError: Cannot read property 'length' of undefined Jun 14 18:53:24 ad1862-x2 volumio[845]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Jun 14 18:53:24 ad1862-x2 volumio[845]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Jun 14 18:53:24 ad1862-x2 volumio[845]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Jun 14 18:53:24 ad1862-x2 volumio[845]: at Parser.emit (events.js:315:20) Jun 14 18:53:24 ad1862-x2 volumio[845]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Jun 14 18:53:24 ad1862-x2 volumio[845]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Jun 14 18:53:24 ad1862-x2 volumio[845]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Jun 14 18:53:24 ad1862-x2 volumio[845]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Jun 14 18:53:24 ad1862-x2 volumio[845]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Jun 14 18:53:24 ad1862-x2 volumio[845]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Jun 14 18:53:24 ad1862-x2 volumio[845]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Jun 14 18:53:24 ad1862-x2 volumio[845]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Jun 14 18:53:24 ad1862-x2 volumio[845]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Jun 14 18:53:24 ad1862-x2 volumio[845]: at IncomingMessage.emit (events.js:327:22) Jun 14 18:53:24 ad1862-x2 volumio[845]: at endReadableNT (internal/streams/readable.js:1327:12) Jun 14 18:53:24 ad1862-x2 volumio[845]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jun 14 18:53:24 ad1862-x2 volumio[845]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 14 18:53:26 ad1862-x2 sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-14 18:52 Jun 14 18:53:26 ad1862-x2 sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"