-- Logs begin at Thu 2019-02-14 19:12:00 KST, end at Fri 2025-06-27 08:26:09 KST. -- Jun 27 08:25:08 volumio-ap2 systemd-timedated[1114]: Changed local time to Fri Jun 27 08:25:08 2025 Jun 27 08:25:08 volumio-ap2 sudo[1112]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:08 volumio-ap2 volumio-time-update[510]: volumio-time-update-util: System time updated successfully. Jun 27 08:25:08 volumio-ap2 systemd[1]: Started Volumio Time Update Utility. Jun 27 08:25:08 volumio-ap2 systemd[1]: Reached target Multi-User System. Jun 27 08:25:08 volumio-ap2 systemd[1]: Reached target Graphical Interface. Jun 27 08:25:08 volumio-ap2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 27 08:25:08 volumio-ap2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Jun 27 08:25:08 volumio-ap2 systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 27 08:25:08 volumio-ap2 systemd[1]: Startup finished in 14.869s (kernel) + 30.186s (userspace) = 45.055s. Jun 27 08:25:08 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:08] [info] asio async_connect error: system:111 (Connection refused) Jun 27 08:25:08 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 27 08:25:08 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:08] [error] handle_connect error: Underlying Transport Error Jun 27 08:25:10 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:10 volumio-ap2 volumio[1090]: info: ----- Volumio3 ---- Jun 27 08:25:10 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:10 volumio-ap2 volumio[1090]: info: ----- System startup ---- Jun 27 08:25:10 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:12 volumio-ap2 volumio[1090]: info: MYVOLUMIO Environment detected Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Plugin folders cleanup Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning into folder /volumio/app/plugins/ Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category audio_interface Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category miscellanea Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category music_service Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category plugins.json Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category system_controller Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category user_interface Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning into folder /data/plugins/ Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category audio_interface Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category music_service Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Scanning category user_interface Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Plugin folders cleanup completed Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: ----- Core plugins startup ---- Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Loading plugins from folder /volumio/app/plugins/ Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Adding plugin upnp to MyMusic Plugins Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Loading plugins from folder /data/plugins/ Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Loading plugin "system"... Jun 27 08:25:13 volumio-ap2 volumio[1090]: info: Loading plugin "appearance"... Jun 27 08:25:13 volumio-ap2 systemd[1]: systemd-fsckd.service: Succeeded. Jun 27 08:25:13 volumio-ap2 sshd[1127]: Accepted password for volumio from 172.16.0.254 port 64258 ssh2 Jun 27 08:25:13 volumio-ap2 sshd[1127]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Jun 27 08:25:13 volumio-ap2 systemd[1]: Created slice User Slice of UID 1000. Jun 27 08:25:14 volumio-ap2 systemd[1]: Starting User Runtime Directory /run/user/1000... Jun 27 08:25:14 volumio-ap2 systemd-logind[517]: New session 1 of user volumio. Jun 27 08:25:14 volumio-ap2 systemd[1]: Started User Runtime Directory /run/user/1000. Jun 27 08:25:14 volumio-ap2 systemd[1]: Starting User Manager for UID 1000... Jun 27 08:25:14 volumio-ap2 systemd[1133]: pam_unix(systemd-user:session): session opened for user volumio by (uid=0) Jun 27 08:25:14 volumio-ap2 sh[412]: timed out Jun 27 08:25:14 volumio-ap2 dhcpcd[461]: timed out Jun 27 08:25:14 volumio-ap2 sh[412]: dhcpcd exited Jun 27 08:25:14 volumio-ap2 dhcpcd[461]: dhcpcd exited Jun 27 08:25:14 volumio-ap2 sh[412]: ifup: failed to bring up eth0 Jun 27 08:25:14 volumio-ap2 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Jun 27 08:25:14 volumio-ap2 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers). Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on GnuPG cryptographic agent and passphrase cache (restricted). Jun 27 08:25:14 volumio-ap2 systemd[1133]: Reached target Paths. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on GnuPG network certificate management daemon. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Reached target Timers. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Starting D-Bus User Message Bus Socket. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on GnuPG cryptographic agent and passphrase cache. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on Sound System. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on GnuPG cryptographic agent (ssh-agent emulation). Jun 27 08:25:14 volumio-ap2 systemd[1133]: Listening on D-Bus User Message Bus Socket. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Reached target Sockets. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Reached target Basic System. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Reached target Default. Jun 27 08:25:14 volumio-ap2 systemd[1133]: Startup finished in 645ms. Jun 27 08:25:14 volumio-ap2 systemd[1]: Started User Manager for UID 1000. Jun 27 08:25:14 volumio-ap2 systemd[1]: Started Session 1 of user volumio. Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Loading plugin "network"... Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Refreshing Cached IP Addresses Jun 27 08:25:16 volumio-ap2 sudo[1155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 08:25:16 volumio-ap2 sudo[1155]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:16 volumio-ap2 sudo[1155]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:16 volumio-ap2 sudo[1157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 08:25:16 volumio-ap2 sudo[1157]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Loading plugin "services"... Jun 27 08:25:16 volumio-ap2 sudo[1157]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Loading plugin "alsa_controller"... Jun 27 08:25:16 volumio-ap2 sudo[1166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 27 08:25:16 volumio-ap2 sudo[1166]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Loading plugin "wizard"... Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Loading plugin "networkfs"... Jun 27 08:25:16 volumio-ap2 volumio[1090]: info: Starting Udev Watcher for removable devices Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Ignoring mount for partition: boot Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Ignoring mount for partition: volumio Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Ignoring mount for partition: volumio_data Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Loading plugin "volumio_command_line_client"... Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Loading plugin "upnp"... Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: [1750980317049] Starting Upmpd Daemon Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Loading plugin "my_music"... Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Loading plugin "mpd"... Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Plugin upnp_browser is not enabled Jun 27 08:25:17 volumio-ap2 volumio[1090]: info: Loading plugin "alarm-clock"... Jun 27 08:25:18 volumio-ap2 volumio[1090]: info: Plugin airplay_emulation is not enabled Jun 27 08:25:18 volumio-ap2 volumio[1090]: info: Loading plugin "last_100"... Jun 27 08:25:18 volumio-ap2 volumio[1090]: info: Loading plugin "webradio"... Jun 27 08:25:18 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:18] [connect] Successful connection Jun 27 08:25:18 volumio-ap2 volumio[1090]: info: Loading plugin "i2s_dacs"... Jun 27 08:25:18 volumio-ap2 volumio[1090]: info: Loading plugin "volumiodiscovery"... Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** For more information see Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 08:25:18 volumio-ap2 volumio[1090]: *** WARNING *** For more information see Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** For more information see Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 27 08:25:18 volumio-ap2 node[1090]: *** WARNING *** For more information see Jun 27 08:25:19 volumio-ap2 volumio[1090]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 27 08:25:19 volumio-ap2 volumio[1090]: info: Discovery: Started advertising with name: Volumio-AP2 Jun 27 08:25:19 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 27 08:25:19 volumio-ap2 volumio[1090]: info: Loading plugin "calmradio"... Jun 27 08:25:20 volumio-ap2 sudo[1166]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:21 volumio-ap2 volumio[1090]: info: Loading plugin "squeezelite_mc"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "outputs"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "albumart"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Plugin example_plugin is not enabled Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "inputs"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "updater_comm"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Plugin mpdemulation is not enabled Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "rest_api"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "websocket"... Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Starting Socket.io Server version 2.3.0 Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Plugin fusiondsp is not enabled Jun 27 08:25:23 volumio-ap2 volumio[1090]: info: Loading plugin "80s80s"... Jun 27 08:25:24 volumio-ap2 volumio[1090]: Forking 3 albumart workers Jun 27 08:25:24 volumio-ap2 volumio[1090]: info: Applying required configuration parameters for plugin 80s80s Jun 27 08:25:24 volumio-ap2 volumio[1090]: info: [1750980324782] [80s80s] API delay: 30 Jun 27 08:25:24 volumio-ap2 volumio[1090]: info: Loading plugin "han_radio"... Jun 27 08:25:25 volumio-ap2 volumio[1090]: info: ControllerHanRadio::constructor Jun 27 08:25:25 volumio-ap2 volumio[1090]: info: [1750980325551] [HanRadio] API delay: 60 Jun 27 08:25:25 volumio-ap2 volumio[1090]: info: Loading plugin "motherearthradio"... Jun 27 08:25:26 volumio-ap2 volumio[1090]: Starting albumart workers Jun 27 08:25:26 volumio-ap2 volumio[1090]: Starting albumart workers Jun 27 08:25:26 volumio-ap2 volumio[1090]: Starting albumart workers Jun 27 08:25:26 volumio-ap2 volumio[1090]: info: Applying required configuration parameters for plugin motherearthradio Jun 27 08:25:26 volumio-ap2 volumio[1090]: info: [1750980326325] [MotherEarth] API delay: 5 Jun 27 08:25:26 volumio-ap2 volumio[1090]: info: Loading plugin "personal_radio"... Jun 27 08:25:28 volumio-ap2 volumio[1090]: info: ControllerPersonalRadio::constructor Jun 27 08:25:28 volumio-ap2 volumio[1090]: info: Loading plugin "radio_paradise"... Jun 27 08:25:28 volumio-ap2 volumio[1090]: info: Applying required configuration parameters for plugin radio_paradise Jun 27 08:25:28 volumio-ap2 volumio[1090]: info: [1750980328705] [RadioParadise] API delay: 5 Jun 27 08:25:28 volumio-ap2 volumio[1090]: info: Loading plugin "Systeminfo"... Jun 27 08:25:29 volumio-ap2 volumio[1090]: info: Loading i18n strings for locale ko Jun 27 08:25:29 volumio-ap2 volumio[1090]: Updating browse sources language Jun 27 08:25:29 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::initPlayerControls Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: Express server listening on port 3000 Jun 27 08:25:30 volumio-ap2 volumio[1090]: [Metrics] WebUI: 20s 931.90ms Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::resetVolumioState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::getcurrentVolume Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:30 volumio-ap2 sudo[1255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 27 08:25:30 volumio-ap2 sudo[1255]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:30 volumio-ap2 sudo[1255]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:30 volumio-ap2 sudo[1257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 27 08:25:30 volumio-ap2 sudo[1257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:30 volumio-ap2 sudo[1257]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: Volumio Network Manager: Network status updated: 2 Jun 27 08:25:30 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jun 27 08:25:30 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jun 27 08:25:30 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jun 27 08:25:30 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: Reloading queue from file Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::setRepeat null single undefined Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::setRandom null Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: Setting Device type: Raspberry PI Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: Completed loading Core Plugins Jun 27 08:25:30 volumio-ap2 volumio[1090]: info: Preparing to generate the ALSA configuration file Jun 27 08:25:30 volumio-ap2 sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 27 08:25:30 volumio-ap2 sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Asound.conf file unchanged, so no further update is needed Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Output device has changed, restarting MPD Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: ___________ START PLUGINS ___________ Jun 27 08:25:31 volumio-ap2 systemd[1]: Started UPnP Renderer front-end to MPD. Jun 27 08:25:31 volumio-ap2 sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: ControllerMpd::onStart: Initializing MPD Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Creating MPD Configuration file Jun 27 08:25:31 volumio-ap2 sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 sudo[1267]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:31 volumio-ap2 sudo[1271]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:31 volumio-ap2 sudo[1273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 08:25:31 volumio-ap2 sudo[1273]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331196] CoreMusicLibrary::Adding element Last_100 Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 27 08:25:31 volumio-ap2 sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331208] CoreMusicLibrary::Adding element Webradio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 sudo[1277]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 08:25:31 volumio-ap2 systemd[1]: Stopping Music Player Daemon... Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Initializing BBC Radios Jun 27 08:25:31 volumio-ap2 sudo[1279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 27 08:25:31 volumio-ap2 sudo[1279]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Adding Calm Radio to Browse Sources Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331382] CoreMusicLibrary::Adding element Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 systemd[1]: mpd.service: Succeeded. Jun 27 08:25:31 volumio-ap2 systemd[1]: Stopped Music Player Daemon. Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Starting proxy server... Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331474] CoreMusicLibrary::Adding element 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331490] CoreMusicLibrary::Adding element Han Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Han Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331505] CoreMusicLibrary::Adding element Mother Earth Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Han Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Mother Earth Radio Jun 27 08:25:31 volumio-ap2 systemd[1]: Starting Music Player Daemon... Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331706] CoreMusicLibrary::Adding element 개인 라디오 Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Han Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Mother Earth Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 개인 라디오 Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [1750980331729] CoreMusicLibrary::Adding element Radio Paradise Jun 27 08:25:31 volumio-ap2 sudo[1292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:31 volumio-ap2 sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Calm Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 80s80s Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Han Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Mother Earth Radio Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source 개인 라디오 Jun 27 08:25:31 volumio-ap2 volumio[1090]: Cannot find translation for source Radio Paradise Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: Volumio Calling Home Jun 27 08:25:31 volumio-ap2 sudo[1292]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Proxy server started on port 34099 Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioRetrievevolume Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:31 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Server discovery started Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Player finder started Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Discovery: adding 73dc6cd0-39ac-4e44-b8f3-bb7026a5c987 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Discovery: Found device Volumio-AP2 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Discovery: this is already registered, 73dc6cd0-39ac-4e44-b8f3-bb7026a5c987 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Discovery: Found device Volumio-AP2 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio-ap2","dsdFormat":null,"card":"2","mixerType":"None","mixer":null} Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: MPD Permissions set Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Upmpdcli Daemon Started Jun 27 08:25:32 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 sudo[1302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Jun 27 08:25:32 volumio-ap2 sudo[1302]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 sudo[1302]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 27 08:25:32 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:32 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 172.16.0.52 from 172.16.0.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/137.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: Volumio called home Jun 27 08:25:32 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Jun 27 08:25:33 volumio-ap2 volumio[1090]: (node:1090) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Jun 27 08:25:33 volumio-ap2 volumio[1090]: (Use `node --trace-deprecation ...` to show where the warning was created) Jun 27 08:25:33 volumio-ap2 volumio[1090]: (node:1090) [DEP0106] DeprecationWarning: crypto.createDecipher is deprecated. Jun 27 08:25:33 volumio-ap2 sudo[1314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Jun 27 08:25:33 volumio-ap2 sudo[1314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetQueue Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreStateMachine::getQueue Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getQueue Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getHwuuid Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getUpdaterChannel Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CALLMETHOD: music_service inputs serialMonitorAction [object Object] Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: inputs , serialMonitorAction Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: Error : CoreCommandRouter::executeOnPlugin: No method [serialMonitorAction] in plugin inputs Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CALLMETHOD: system_controller motivocontrol displaySelection [object Object] Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: motivocontrol , displaySelection Jun 27 08:25:33 volumio-ap2 sudo[1314]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetVisibleSources Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: Received Get System Info Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: Discovery: Getting this device information Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: Listing playlists Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetQueue Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CoreStateMachine::getQueue Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: CorePlayQueue::getQueue Jun 27 08:25:33 volumio-ap2 sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Jun 27 08:25:33 volumio-ap2 sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:33 volumio-ap2 sudo[1318]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:33 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Jun 27 08:25:33 volumio-ap2 sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 27 08:25:33 volumio-ap2 sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:33 volumio-ap2 systemd[1]: Reloading. Jun 27 08:25:33 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:33] [connect] Successful connection Jun 27 08:25:34 volumio-ap2 volumio-remote-updater[519]: [2025-06-27 08:25:34] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1750980333 101 Jun 27 08:25:34 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Jun 27 08:25:34 volumio-ap2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jun 27 08:25:34 volumio-ap2 mpd[1294]: Jun 27 08:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 27 08:25:34 volumio-ap2 volumio[1090]: Upnp client error: Error: This socket has been ended by the other party Jun 27 08:25:34 volumio-ap2 systemd[1]: serial-getty@ttyAMA0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 27 08:25:34 volumio-ap2 systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jun 27 08:25:35 volumio-ap2 systemd[1]: Started Music Player Daemon. Jun 27 08:25:35 volumio-ap2 sudo[1273]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:35 volumio-ap2 sudo[1279]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:35 volumio-ap2 sudo[1321]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:35 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:35 volumio-ap2 volumio[1269]: Generating RSA private key, 4096 bit long modulus (2 primes) Jun 27 08:25:35 volumio-ap2 sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:35 volumio-ap2 sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:35 volumio-ap2 sudo[1357]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:35 volumio-ap2 volumio[1090]: error: MPD error: The expression evaluated to a falsy value: Jun 27 08:25:35 volumio-ap2 volumio[1090]: assert.ok(self.idling) Jun 27 08:25:35 volumio-ap2 volumio[1090]: error: The expression evaluated to a falsy value: Jun 27 08:25:35 volumio-ap2 volumio[1090]: assert.ok(self.idling) Jun 27 08:25:35 volumio-ap2 volumio[1090]: error: updateQueue error: null Jun 27 08:25:35 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Jun 27 08:25:35 volumio-ap2 volumio[1090]: info: MPD running with PID1294 Jun 27 08:25:35 volumio-ap2 volumio[1090]: ,establishing connection Jun 27 08:25:35 volumio-ap2 volumio[1090]: error: updateQueue error: null Jun 27 08:25:35 volumio-ap2 sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Jun 27 08:25:35 volumio-ap2 sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:35 volumio-ap2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Jun 27 08:25:35 volumio-ap2 kernel: process '/opt/squeezelite/squeezelite' started with executable stack Jun 27 08:25:35 volumio-ap2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Jun 27 08:25:35 volumio-ap2 sudo[1360]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:36 volumio-ap2 volumio[1090]: info: CALLMETHOD: system_controller system enableLiveLog true Jun 27 08:25:36 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , enableLiveLog Jun 27 08:25:36 volumio-ap2 volumio[1090]: info: Launching a new LiveLog session Jun 27 08:25:36 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:36 volumio-ap2 sudo[1368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:36 volumio-ap2 sudo[1368]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:36 volumio-ap2 sudo[1368]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:36 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:37 volumio-ap2 sudo[1385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:37 volumio-ap2 sudo[1385]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:37 volumio-ap2 sudo[1385]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:37 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:37 volumio-ap2 sudo[1388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:37 volumio-ap2 sudo[1388]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:37 volumio-ap2 sudo[1388]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:38 volumio-ap2 systemd[1]: systemd-timedated.service: Succeeded. Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:38 volumio-ap2 sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:38 volumio-ap2 sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:38 volumio-ap2 sudo[1396]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Jun 27 08:25:38 volumio-ap2 sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Jun 27 08:25:38 volumio-ap2 sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:38 volumio-ap2 sudo[1399]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: Completed starting Core Plugins Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: ----- MyVolumio plugins startup ---- Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: ------------------------------------------- Jun 27 08:25:38 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 27 08:25:40 volumio-ap2 volumio[1090]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: Preload queue cleared Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreStateMachine::ClearQueue Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreStateMachine::stop Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CorePlayQueue::clearPlayQueue Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CorePlayQueue::saveQueue Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushQueue Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CoreStateMachine::addQueueItems Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: CorePlayQueue::addQueueItems Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: Preload queue cleared Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: Adding Item to queue: kbs/0 Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: Exploding uri kbs/0 in service han_radio Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342560] [HanRadio] uris ===== kbs,0 Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342560] [HanRadio] channel ===== 0 Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342574] [HanRadio] radioType: kbs Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342575] [HanRadio] streamUrl: https://cfpwwwapi.kbs.co.kr/api/v1/landing/live/channel_code/24 Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342754] [HanRadio] realUrl ===== https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342795] [HanRadio] realUrl ===== https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA Jun 27 08:25:42 volumio-ap2 volumio[1090]: info: [1750980342800] [HanRadio] scheduleUrl: https://static.api.kbs.co.kr/mediafactory/v1/schedule/weekly?local_station_code=00&channel_code=24 Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343100] [HanRadio] title: 출발 FM과 함께 Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343101] [HanRadio] image: https://programres.kbs.co.kr/r2002-0282/2023/12/1/1701414380997_487304.jpg Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushQueue Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CorePlayQueue::saveQueue Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::updateTrackBlock Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrackBlock Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPlay Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::play index 0 Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::stop Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::play index undefined Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::startPlaybackTimer Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343147] [HanRadio] clearAddPlayTrack ===== + clearAddPlayTrack Jun 27 08:25:43 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand stop Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: sendMpdCommand stop took 14 milliseconds Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343164] [HanRadio] clearAddPlayTrack ===== + stop Jun 27 08:25:43 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand clear Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: sendMpdCommand clear took 2 milliseconds Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343170] [HanRadio] clearAddPlayTrack ===== https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA Jun 27 08:25:43 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: sendMpdCommand add "https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA" took 22 milliseconds Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 21ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 17ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343198] [HanRadio] clearAddPlayTrack ===== + info Jun 27 08:25:43 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand play Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Jun 27 08:25:43 volumio-ap2 volumio[1090]: ---------------------------- MPD announces system playlist update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: Ignoring MPD Status Update Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 47ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 46ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: sendMpdCommand play took 19 milliseconds Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 18ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: ------------------------------ 15ms Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343239] [HanRadio] clearAddPlayTrack ===== + play Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 27 08:25:43 volumio-ap2 volumio[1090]: info: [1750980343242] [HanRadio] clearAddPlayTrack setMetaData Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 20 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 22 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 19 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Jun 27 08:25:45 volumio-ap2 volumio[1090]: ---------------------------- MPD announces state update: player Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::getState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand status Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 41 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 24 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 21 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 20 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 16 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: sendMpdCommand status took 11 milliseconds Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseState Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus stop Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus play Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Received an update from plugin. extracting info from payload Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:45 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus play Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: Received an update from plugin. extracting info from payload Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:45 volumio-ap2 volumio[1090]: info: ------------------------------ 145ms Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ------------------------------ 164ms Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ------------------------------ 160ms Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 102 milliseconds Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 95 milliseconds Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: sendMpdCommand playlistinfo took 94 milliseconds Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: ControllerMpd::parseTrackInfo Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"209 Kbps","isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: Received an update from plugin. extracting info from payload Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"209 Kbps","isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: Received an update from plugin. extracting info from payload Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ControllerMpd::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::servicePushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"209 Kbps","isStreaming":false,"title":"1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1751153037&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUxMTUzMDM3fX19XX0_&Signature=NwbKq8p3qrZJQ-5XsW6DpT6Pi1h5zrC-fiWHcuYgzqcSWLKY1P61Pc3asc96MeI4WyGgdFCJ4muIQH1bsQ--W-oxtstiiK15hsHq0lqWS1PT9zDaznz8bIteSIRpqDJqLMMCPMhmoRi9R5IZ8v3UlIAXdg6Q9egB-FyAR0Op2PIEJLYFlxVE8NaNHzlSIFnvgJcErhBkLPCppChIu75X1hRTuadyw~Zp2uU~A5fmfoweiyWOtpyFk4WKBZ9jtPRH7MB16CP08XHqZp8JZZaoqG8J8fgVGXkpX5nLn-bAMLzfIdswNZEca33Dd339y41sXHmOMTk3RZlQH2EMjGUFvQ__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":""} Jun 27 08:25:46 volumio-ap2 volumio[1090]: verbose: CURRENT POSITION 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState stateService play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::syncState currentStatus play Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: Received an update from plugin. extracting info from payload Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ------------------------------ 252ms Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ------------------------------ 221ms Jun 27 08:25:46 volumio-ap2 volumio[1090]: info: ------------------------------ 219ms Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin bluetooth to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin multiroom to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin metavolumio to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin cd_controller to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin qobuzconnect to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin smart_inputs to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: Adding plugin tidalconnect to MyMusic Plugins Jun 27 08:25:47 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: Starting MyVolumio Remote Streaming Endpoints Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: MyVolumio login type: Token Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jun 27 08:25:50 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: Starting Streaming Service Transparent Proxy Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: Streaming services startup Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: Starting Streaming Daemon Jun 27 08:25:53 volumio-ap2 sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 08:25:53 volumio-ap2 sudo[1483]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jun 27 08:25:53 volumio-ap2 sudo[1483]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:53 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:25:53 volumio-ap2 volumio[1090]: error: Cannot start Volumio Streaming Daemon Jun 27 08:25:53 volumio-ap2 volumio[1090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 08:25:53 volumio-ap2 volumio[1090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 08:25:53 volumio-ap2 volumio[1090]: Upnp client error: Error: This socket has been ended by the other party Jun 27 08:25:53 volumio-ap2 volumio[1090]: Upnp client error: Error: This socket has been ended by the other party Jun 27 08:25:54 volumio-ap2 ntpd[655]: Soliciting pool server 194.0.5.123 Jun 27 08:25:54 volumio-ap2 volumio[1269]: .................................................................................++++ Jun 27 08:25:54 volumio-ap2 volumio[1090]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jun 27 08:25:55 volumio-ap2 ntpd[655]: Soliciting pool server 106.247.248.106 Jun 27 08:25:55 volumio-ap2 volumio[1090]: info: MyVolumio token set successfully Jun 27 08:25:55 volumio-ap2 volumio[1090]: info: MYVOLUMIO: Adding device Jun 27 08:25:55 volumio-ap2 volumio[1090]: info: MYVOLUMIO: Evaluating Server Jun 27 08:25:56 volumio-ap2 volumio[1090]: STREAMING PROXY: Starting server on port 3245 Jun 27 08:25:56 volumio-ap2 volumio[1090]: Node JS runtime: 14 Jun 27 08:25:56 volumio-ap2 ntpd[655]: Soliciting pool server 203.32.26.46 Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: MyVolumio status changed Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Streaming services startup Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Starting Streaming Daemon Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Removing browser output: myVolumio user plan is not superstar Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Removing audio output: Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Stoppping Tunnel 1 Jun 27 08:25:56 volumio-ap2 sudo[1512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 27 08:25:56 volumio-ap2 sudo[1512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:56 volumio-ap2 sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jun 27 08:25:56 volumio-ap2 sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 27 08:25:56 volumio-ap2 sudo[1512]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:56 volumio-ap2 ntpd[655]: Soliciting pool server 121.174.142.82 Jun 27 08:25:56 volumio-ap2 volumio[1090]: error: Cannot start Volumio Streaming Daemon Jun 27 08:25:56 volumio-ap2 volumio[1090]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 27 08:25:56 volumio-ap2 volumio[1090]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 27 08:25:56 volumio-ap2 sudo[1514]: pam_unix(sudo:session): session closed for user root Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Setting Geolocation for MyVolumio to as1 Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:56 volumio-ap2 volumio[1090]: info: Remote SSH Stopped Jun 27 08:25:56 volumio-ap2 ntpd[655]: Soliciting pool server 158.247.202.103 Jun 27 08:25:57 volumio-ap2 volumio[1090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 27 08:25:57 volumio-ap2 ntpd[655]: Soliciting pool server 240b:400d:2:7cc8:f0d6:5ac6:f883:48db Jun 27 08:25:57 volumio-ap2 ntpd[655]: Soliciting pool server 221.151.118.78 Jun 27 08:25:57 volumio-ap2 volumio[1090]: info: Updating MyVolumio device info Jun 27 08:25:57 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:25:57 volumio-ap2 ntpd[655]: Soliciting pool server 175.195.167.194 Jun 27 08:25:58 volumio-ap2 volumio[1090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 27 08:25:58 volumio-ap2 ntpd[655]: Soliciting pool server 121.174.142.81 Jun 27 08:25:59 volumio-ap2 volumio[1269]: ...................++++ Jun 27 08:25:59 volumio-ap2 volumio[1269]: e is 65537 (0x010001) Jun 27 08:26:00 volumio-ap2 volumio[1269]: writing RSA key Jun 27 08:26:01 volumio-ap2 volumio[1090]: info: MYVOLUMIO: Adding device Jun 27 08:26:01 volumio-ap2 volumio[1090]: info: MYVOLUMIO: Evaluating Server Jun 27 08:26:01 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 27 08:26:01 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:01 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 27 08:26:02 volumio-ap2 ntpd[655]: receive: Unexpected origin timestamp 0xec085589.a0cafd82 does not match aorg 0000000000.00000000 from server@106.247.248.106 xmt 0xec08558a.d8353112 Jun 27 08:26:02 volumio-ap2 ntpd[655]: receive: Unexpected origin timestamp 0xec085589.a0c20bb2 does not match aorg 0000000000.00000000 from server@121.174.142.82 xmt 0xec08558a.d85068f2 Jun 27 08:26:03 volumio-ap2 volumio-remote-updater[519]: No test mode Jun 27 08:26:03 volumio-ap2 volumio-remote-updater[519]: No alpha test mode Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: Setting Geolocation for MyVolumio to as1 Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 27 08:26:03 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 27 08:26:04 volumio-ap2 volumio[1090]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jun 27 08:26:04 volumio-ap2 volumio[1090]: info: Updating MyVolumio device info Jun 27 08:26:04 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:04 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:04 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 27 08:26:05 volumio-ap2 volumio[1090]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jun 27 08:26:06 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 27 08:26:06 volumio-ap2 volumio[1090]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jun 27 08:26:06 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioGetState Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioStop Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::stop Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::stPlaybackTimer Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::updateTrackBlock Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrackBlock Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::pushState Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreCommandRouter::volumioPushState Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreStateMachine::serviceStop Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CorePlayQueue::getTrack 0 Jun 27 08:26:08 volumio-ap2 volumio[1090]: info: CoreCommandRouter::serviceStop Jun 27 08:26:08 volumio-ap2 volumio[1090]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 08:26:08 volumio-ap2 volumio[1090]: TypeError: Cannot read property 'logger' of undefined Jun 27 08:26:08 volumio-ap2 volumio[1090]: at ControllerHanRadio.stop (/data/plugins/music_service/han_radio/index.js:290:10) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:371:25) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1269:33) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1244:19) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at CoreCommandRouter.volumioStop (/volumio/app/index.js:91:28) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:262:33) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at Socket.emit (events.js:315:20) Jun 27 08:26:08 volumio-ap2 volumio[1090]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 27 08:26:08 volumio-ap2 volumio[1090]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 27 08:26:08 volumio-ap2 volumio[1090]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 27 08:26:09 volumio-ap2 sudo[1610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-27 08:25 Jun 27 08:26:09 volumio-ap2 sudo[1610]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"