-- Logs begin at Wed 2025-06-18 03:15:34 UTC, end at Wed 2025-06-18 03:17:29 UTC. -- Jun 18 03:16:11 volumio-home systemd[1]: Time has been changed Jun 18 03:16:11 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:11] [info] asio async_connect error: system:111 (Connection refused) Jun 18 03:16:11 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:11] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jun 18 03:16:11 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:11] [error] handle_connect error: Underlying Transport Error Jun 18 03:16:12 volumio-home wireless.js[670]: trying... Jun 18 03:16:12 volumio-home sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 18 03:16:12 volumio-home sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:12 volumio-home sudo[1003]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:12 volumio-home wpa_supplicant[947]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Jun 18 03:16:12 volumio-home wpa_supplicant[947]: wlan0: Associated with 80:ca:4b:37:a4:2f Jun 18 03:16:12 volumio-home wpa_supplicant[947]: wlan0: WPA: Key negotiation completed with 80:ca:4b:37:a4:2f [PTK=CCMP GTK=CCMP] Jun 18 03:16:12 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:ca:4b:37:a4:2f completed [id=0 id_str=] Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: carrier acquired Jun 18 03:16:12 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=KR Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: soliciting an IPv6 router Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: using static address 192.168.123.107 Jun 18 03:16:12 volumio-home avahi-daemon[566]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.123.107. Jun 18 03:16:12 volumio-home avahi-daemon[566]: New relevant interface wlan0.IPv4 for mDNS. Jun 18 03:16:12 volumio-home avahi-daemon[566]: Registering new address record for 192.168.123.107 on wlan0.IPv4. Jun 18 03:16:12 volumio-home dhcpcd[943]: eth0: adding route to 192.168.123.0/24 Jun 18 03:16:12 volumio-home dhcpcd[943]: eth0: adding default route via 192.168.123.1 Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: router 192.168.1.1 requires a host route Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: adding host route to 192.168.123.107 via 127.0.0.1 Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: adding route to 192.168.123.0/24 Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: adding host route to 192.168.1.1 Jun 18 03:16:12 volumio-home dhcpcd[943]: wlan0: adding default route via 192.168.1.1 Jun 18 03:16:12 volumio-home dhcpcd[943]: eth0: removing route to 192.168.123.0/24 Jun 18 03:16:12 volumio-home dhcpcd[943]: eth0: removing default route via 192.168.123.1 Jun 18 03:16:13 volumio-home wireless.js[670]: trying... Jun 18 03:16:13 volumio-home sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 18 03:16:13 volumio-home sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:13 volumio-home sudo[1050]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:13 volumio-home wireless.js[670]: Connected to: ----U+NetA42C_5G Jun 18 03:16:13 volumio-home wireless.js[670]: ---- Jun 18 03:16:13 volumio-home wireless.js[670]: ... joined AP, wlan0 IPv4 is 192.168.123.107, ipV6 is undefined Jun 18 03:16:13 volumio-home wireless.js[670]: It's done! AP Jun 18 03:16:13 volumio-home systemd[1]: Started Wireless Services. Jun 18 03:16:13 volumio-home systemd[1]: Starting Volumio Backend Module... Jun 18 03:16:13 volumio-home systemd[1]: Started Volumio Backend Module. Jun 18 03:16:13 volumio-home systemd[1]: Starting Multi-User System. Jun 18 03:16:13 volumio-home systemd[1]: Reached target Multi-User System. Jun 18 03:16:13 volumio-home systemd[1]: Starting Graphical Interface. Jun 18 03:16:13 volumio-home systemd[1]: Reached target Graphical Interface. Jun 18 03:16:13 volumio-home systemd[1]: Starting Update UTMP about System Runlevel Changes... Jun 18 03:16:13 volumio-home systemd[1]: Started Update UTMP about System Runlevel Changes. Jun 18 03:16:13 volumio-home systemd[1]: Startup finished in 10.313s (kernel) + 20.545s (userspace) = 30.858s. Jun 18 03:16:14 volumio-home ntpd[654]: Listen normally on 4 wlan0 192.168.123.107 UDP 123 Jun 18 03:16:14 volumio-home ntpd[654]: peers refreshed Jun 18 03:16:17 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:17 volumio-home volumio[1061]: info: ----- Volumio2 ---- Jun 18 03:16:17 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:17 volumio-home volumio[1061]: info: ----- System startup ---- Jun 18 03:16:17 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:17 volumio-home volumio[1061]: info: Plugin folders cleanup Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning into folder /volumio/app/plugins/ Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category audio_interface Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category miscellanea Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category music_service Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category plugins.json Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category system_controller Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category user_interface Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning into folder /data/plugins/ Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category miscellanea Jun 18 03:16:17 volumio-home volumio[1061]: info: Scanning category music_service Jun 18 03:16:17 volumio-home volumio[1061]: info: Plugin folders cleanup completed Jun 18 03:16:17 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:17 volumio-home volumio[1061]: info: ----- Core plugins startup ---- Jun 18 03:16:17 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:17 volumio-home volumio[1061]: info: Loading plugins from folder /volumio/app/plugins/ Jun 18 03:16:17 volumio-home volumio[1061]: info: Loading plugins from folder /data/plugins/ Jun 18 03:16:17 volumio-home volumio[1061]: info: Loading plugin "system"... Jun 18 03:16:17 volumio-home volumio[1061]: info: Loading plugin "appearance"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "network"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "services"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "alsa_controller"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "wizard"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "volumio_command_line_client"... Jun 18 03:16:18 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:18 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:18 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:18 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "upnp"... Jun 18 03:16:18 volumio-home volumio[1061]: info: [1750216578937] Starting Upmpd Daemon Jun 18 03:16:18 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "my_music"... Jun 18 03:16:18 volumio-home volumio[1061]: info: Loading plugin "mpd"... Jun 18 03:16:19 volumio-home volumio[1061]: info: Loading plugin "upnp_browser"... Jun 18 03:16:20 volumio-home volumio[1061]: info: Loading plugin "networkfs"... Jun 18 03:16:20 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 03:16:20 volumio-home volumio[1061]: info: Loading plugin "alarm-clock"... Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "airplay_emulation"... Jun 18 03:16:21 volumio-home volumio[1061]: info: [1750216581396] Starting Shairport Sync Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "last_100"... Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "webradio"... Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "i2s_dacs"... Jun 18 03:16:21 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "volumiodiscovery"... Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** For more information see Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 03:16:21 volumio-home node[1061]: *** WARNING *** For more information see Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** For more information see Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 18 03:16:21 volumio-home volumio[1061]: *** WARNING *** For more information see Jun 18 03:16:21 volumio-home volumio[1061]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 18 03:16:21 volumio-home volumio[1061]: Discovery: StartAdv! undefined Jun 18 03:16:21 volumio-home volumio[1061]: Discovery: Started advertising... Volumio-home - undefined Jun 18 03:16:21 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 03:16:21 volumio-home volumio[1061]: info: Loading plugin "autostart"... Jun 18 03:16:21 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:21] [connect] Successful connection Jun 18 03:16:22 volumio-home volumio[1061]: info: Applying required configuration parameters for plugin autostart Jun 18 03:16:22 volumio-home volumio[1061]: info: Loading plugin "youtube"... Jun 18 03:16:25 volumio-home volumio[1061]: info: Loading plugin "albumart"... Jun 18 03:16:25 volumio-home volumio[1061]: info: Plugin example_plugin is not enabled Jun 18 03:16:25 volumio-home volumio[1061]: info: Loading plugin "updater_comm"... Jun 18 03:16:25 volumio-home volumio[1061]: info: Plugin mpdemulation is not enabled Jun 18 03:16:25 volumio-home volumio[1061]: info: Loading plugin "rest_api"... Jun 18 03:16:25 volumio-home volumio[1061]: info: Loading plugin "websocket"... Jun 18 03:16:25 volumio-home volumio[1061]: info: Loading plugin "lastfm"... Jun 18 03:16:25 volumio-home volumio[1061]: Forking 3 albumart workers Jun 18 03:16:27 volumio-home volumio[1061]: Starting albumart workers Jun 18 03:16:27 volumio-home volumio[1061]: Starting albumart workers Jun 18 03:16:27 volumio-home volumio[1061]: Starting albumart workers Jun 18 03:16:27 volumio-home volumio[1061]: info: [LastFM] scrobbler initiated! Jun 18 03:16:27 volumio-home volumio[1061]: info: [LastFM] extended logging: false Jun 18 03:16:27 volumio-home volumio[1061]: info: [LastFM] try scrobble stream/radio plays: true Jun 18 03:16:27 volumio-home volumio[1061]: info: Loading plugin "minidlna"... Jun 18 03:16:28 volumio-home volumio[1061]: info: Applying required configuration parameters for plugin minidlna Jun 18 03:16:28 volumio-home volumio[1061]: info: Plugin 80s80s is not enabled Jun 18 03:16:28 volumio-home volumio[1061]: info: Loading plugin "personal_radio"... Jun 18 03:16:30 volumio-home volumio[1061]: info: ControllerPersonalRadio::constructor Jun 18 03:16:30 volumio-home volumio[1061]: info: Loading plugin "spop"... Jun 18 03:16:31 volumio-home volumio[1061]: info: ___________ START PLUGINS ___________ Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591601] CoreMusicLibrary::Adding element Media Servers Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591619] CoreMusicLibrary::Adding element Last_100 Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591621] CoreMusicLibrary::Adding element Webradio Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 03:16:31 volumio-home volumio[1061]: info: Youtube::onStart Adding to browse sources Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591627] CoreMusicLibrary::Adding element Youtube Jun 18 03:16:31 volumio-home volumio[1061]: info: Performing onStart action Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591634] CoreMusicLibrary::Adding element LastFM Jun 18 03:16:31 volumio-home volumio[1061]: info: Loading i18n strings for locale ko Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 18 03:16:31 volumio-home volumio[1061]: info: [1750216591691] CoreMusicLibrary::Adding element 개인 라디오 Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 18 03:16:31 volumio-home volumio[1061]: [SpotifyConnect] Creating VLS config file Jun 18 03:16:31 volumio-home sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: [SpotifyConnect] Starting metadata listener Jun 18 03:16:31 volumio-home sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:31 volumio-home sudo[1111]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:31 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:31 volumio-home volumio[1061]: info: ----- MyVolumio plugins startup ---- Jun 18 03:16:31 volumio-home volumio[1061]: info: ------------------------------------------- Jun 18 03:16:31 volumio-home volumio[1061]: info: Loading plugins from folder /myvolumio/plugins Jun 18 03:16:31 volumio-home volumio[1061]: info: Loading plugins from folder /data/myvolumio/plugins Jun 18 03:16:31 volumio-home volumio[1061]: info: Loading i18n strings for locale ko Jun 18 03:16:31 volumio-home sudo[1119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Jun 18 03:16:31 volumio-home sudo[1119]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:31 volumio-home volumio[1061]: Updating browse sources language Jun 18 03:16:31 volumio-home volumio[1061]: Cannot find translation for sourceMedia Servers Jun 18 03:16:31 volumio-home volumio[1061]: Cannot find translation for sourceYoutube Jun 18 03:16:31 volumio-home volumio[1061]: Cannot find translation for sourceLastFM Jun 18 03:16:31 volumio-home volumio[1061]: Cannot find translation for source개인 라디오 Jun 18 03:16:31 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jun 18 03:16:31 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jun 18 03:16:31 volumio-home sudo[1119]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::initPlayerControls Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:31 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 18 03:16:32 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 18 03:16:32 volumio-home volumio[1061]: info: BOOT COMPLETED Jun 18 03:16:32 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 03:16:32 volumio-home volumio[1125]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jun 18 03:16:32 volumio-home volumio[1125]: Reading Config from "/tmp/volspotify.toml" Jun 18 03:16:32 volumio-home volumio[1125]: Malformed config key: missing field `Authentication` Jun 18 03:16:34 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jun 18 03:16:34 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jun 18 03:16:34 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jun 18 03:16:34 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jun 18 03:16:34 volumio-home volumio[1135]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jun 18 03:16:34 volumio-home volumio[1135]: Reading Config from "/tmp/volspotify.toml" Jun 18 03:16:34 volumio-home volumio[1135]: Malformed config key: missing field `Authentication` Jun 18 03:16:36 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jun 18 03:16:36 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jun 18 03:16:36 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jun 18 03:16:36 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jun 18 03:16:36 volumio-home volumio[1138]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jun 18 03:16:36 volumio-home volumio[1138]: Reading Config from "/tmp/volspotify.toml" Jun 18 03:16:36 volumio-home volumio[1138]: Malformed config key: missing field `Authentication` Jun 18 03:16:36 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:36] [connect] Successful connection Jun 18 03:16:37 volumio-home volumio[1061]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreCommandRouter::Close All Modals sent Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreCommandRouter::Close All Modals sent Jun 18 03:16:37 volumio-home volumio[1061]: Express server listening on port 3000 Jun 18 03:16:37 volumio-home volumio[1061]: Volumio Calling Home Jun 18 03:16:37 volumio-home volumio[1061]: info: Starting minidlna.service Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreStateMachine::resetVolumioState Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreStateMachine::getcurrentVolume Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreCommandRouter::volumioRetrievevolume Jun 18 03:16:37 volumio-home sudo[1149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Jun 18 03:16:37 volumio-home sudo[1149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:37 volumio-home volumio[1061]: info: CoreStateMachine::updateTrackBlock Jun 18 03:16:37 volumio-home volumio[1061]: info: CorePlayQueue::getTrackBlock Jun 18 03:16:37 volumio-home systemd[1]: Starting MiniDLNA UPnP-A/V and DLNA media server... Jun 18 03:16:37 volumio-home systemd[1]: Started MiniDLNA UPnP-A/V and DLNA media server. Jun 18 03:16:37 volumio-home sudo[1149]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:38 volumio-home volumio[1061]: info: Setting Device type: Raspberry PI Jun 18 03:16:38 volumio-home volumio[1061]: [SpotifyConnect] Vollibrespot Daemon service started! Jun 18 03:16:38 volumio-home volumio[1061]: SpotifyConnect: 6368.374ms Jun 18 03:16:38 volumio-home volumio[1061]: info: MPD running with PID572 ,establishing connection Jun 18 03:16:38 volumio-home volumio-remote-updater[537]: [2025-06-18 03:16:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1750216596 101 Jun 18 03:16:38 volumio-home volumio[1061]: info: VolumeController:: Volume=40 Mute =false Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:38 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:38 volumio-home volumio[1061]: info: start of minidlna.service succeeded. Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 18 03:16:38 volumio-home volumio[1061]: info: Jun 18 03:16:38 volumio-home volumio[1061]: ---------------------------- Client requests Volume 40 Jun 18 03:16:38 volumio-home volumio[1061]: info: VolumeController::SetAlsaVolume40 Jun 18 03:16:38 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Jun 18 03:16:38 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Jun 18 03:16:38 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Jun 18 03:16:38 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Jun 18 03:16:38 volumio-home volumio[1173]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Jun 18 03:16:38 volumio-home volumio[1173]: Reading Config from "/tmp/volspotify.toml" Jun 18 03:16:38 volumio-home volumio[1173]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Jun 18 03:16:38 volumio-home volumio[1061]: info: Reloading queue from file Jun 18 03:16:38 volumio-home volumio[1061]: info: Jun 18 03:16:38 volumio-home volumio[1061]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Jun 18 03:16:38 volumio-home volumio[1061]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Jun 18 03:16:38 volumio-home volumio[1061]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Jun 18 03:16:38 volumio-home volumio[1061]: at emitOne (events.js:116:13) Jun 18 03:16:38 volumio-home volumio[1061]: at Socket.emit (events.js:211:7) Jun 18 03:16:38 volumio-home volumio[1061]: at addChunk (_stream_readable.js:263:12) Jun 18 03:16:38 volumio-home volumio[1061]: at readableAddChunk (_stream_readable.js:246:13) Jun 18 03:16:38 volumio-home volumio[1061]: at Socket.Readable.push (_stream_readable.js:208:10) Jun 18 03:16:38 volumio-home volumio[1061]: at Pipe.onread (net.js:607:20) Jun 18 03:16:38 volumio-home volumio[1173]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Jun 18 03:16:38 volumio-home volumio[1061]: null Jun 18 03:16:38 volumio-home volumio[1061]: info: VolumeController::Volume 40 Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:38 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:38 volumio-home volumio[1061]: info: Setting volume on startup at 40 Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::setRepeat true single undefined Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:38 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:39 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreStateMachine::setRandom false Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:39 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:39 volumio-home sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jun 18 03:16:39 volumio-home sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:39 volumio-home sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Jun 18 03:16:39 volumio-home systemd[1]: Starting UPnP Renderer front-end to MPD... Jun 18 03:16:39 volumio-home sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 18 03:16:39 volumio-home systemd[1]: Started UPnP Renderer front-end to MPD. Jun 18 03:16:39 volumio-home sudo[1178]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:39 volumio-home systemd[1]: Starting ShairportSync AirTunes receiver... Jun 18 03:16:39 volumio-home systemd[1]: Started ShairportSync AirTunes receiver. Jun 18 03:16:39 volumio-home sudo[1181]: pam_unix(sudo:session): session closed for user root Jun 18 03:16:39 volumio-home shairport-sync[1193]: Successful Startup Jun 18 03:16:39 volumio-home volumio[1061]: Discovery: adding 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jun 18 03:16:39 volumio-home volumio[1061]: info: mDNS: Found device Volumio-home Jun 18 03:16:39 volumio-home volumio[1061]: info: [1750216599617] Shairport-Sync Started Jun 18 03:16:39 volumio-home volumio[1061]: Error adding Membership: Error: addMembership EINVAL Jun 18 03:16:39 volumio-home volumio[1061]: info: Upmpdcli Daemon Started Jun 18 03:16:39 volumio-home volumio[1061]: Discovery: this is already registered, 82fd70b1-be72-4c2a-9c12-b70e12e22616 Jun 18 03:16:39 volumio-home volumio[1061]: info: mDNS: Found device Volumio-home Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreCommandRouter::volumioGetState Jun 18 03:16:39 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:39 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:40 volumio-home volumio[1061]: info: CoreCommandRouter::volumioGetState Jun 18 03:16:40 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:40 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:40 volumio-home volumio[1061]: info: CoreCommandRouter::volumioGetBrowseSources Jun 18 03:16:40 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 18 03:16:40 volumio-home volumio[1061]: Plugin multiroom or method getMultiroom not found Jun 18 03:16:40 volumio-home volumio[1061]: info: Listing playlists Jun 18 03:16:42 volumio-home volumio[1061]: info: AutoStart - getting queue Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreCommandRouter::volumioGetQueue Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreStateMachine::getQueue Jun 18 03:16:42 volumio-home volumio[1061]: info: CorePlayQueue::getQueue Jun 18 03:16:42 volumio-home volumio[1061]: info: AutoStart - start playing -> queue is not empty Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPlay Jun 18 03:16:42 volumio-home volumio[1061]: UNSET VOLATILE Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreStateMachine::play index undefined Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:16:42 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreStateMachine::startPlaybackTimer Jun 18 03:16:42 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:42 volumio-home volumio[1061]: info: [1750216602074] ControllerWebradio::clearAddPlayTrack Jun 18 03:16:42 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand stop Jun 18 03:16:42 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:42 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:42 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand clear Jun 18 03:16:42 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:42 volumio-home volumio[1061]: info: Checking Spotify Web API Jun 18 03:16:42 volumio-home volumio[1061]: info: Jun 18 03:16:42 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:16:42 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:16:42 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:42 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99530397" Jun 18 03:16:42 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:42 volumio-home volumio[1061]: info: Jun 18 03:16:42 volumio-home volumio[1061]: info: ------------------------------ 12ms Jun 18 03:16:42 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:42 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 18 03:16:42 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand play Jun 18 03:16:42 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:42 volumio-home volumio[1061]: info: Jun 18 03:16:42 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:16:42 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:16:42 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:42 volumio-home volumio[1061]: info: ------------------------------ 12ms Jun 18 03:16:45 volumio-home volumio[1061]: info: CoreCommandRouter::volumioGetQueue Jun 18 03:16:45 volumio-home volumio[1061]: info: CoreStateMachine::getQueue Jun 18 03:16:45 volumio-home volumio[1061]: info: CorePlayQueue::getQueue Jun 18 03:16:45 volumio-home volumio[1061]: info: No input data Jun 18 03:16:45 volumio-home volumio[1061]: info: No input data Jun 18 03:16:45 volumio-home volumio[1061]: info: No input data Jun 18 03:16:45 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:46 volumio-home volumio[1061]: info: No input data Jun 18 03:16:48 volumio-home volumio[1061]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jun 18 03:16:48 volumio-home volumio[1061]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 18 03:16:48 volumio-home volumio[1061]: Dload Upload Total Spent Left Speed Jun 18 03:16:48 volumio-home volumio[1061]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jun 18 03:16:48 volumio-home volumio[1061]: retrying in 5 seconds, trial 0 Jun 18 03:16:48 volumio-home volumio[1061]: Volumio Calling Home Jun 18 03:16:50 volumio-home volumio[1061]: info: Jun 18 03:16:50 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:16:50 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:50 volumio-home volumio[1061]: info: Jun 18 03:16:50 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:16:50 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:16:50 volumio-home volumio[1061]: info: Jun 18 03:16:50 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:16:50 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:50 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:16:50 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:50 volumio-home volumio[1061]: info: ------------------------------ 31ms Jun 18 03:16:50 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:50 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:16:50 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:16:50 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:50 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Mirza Selimovic - Plakaces","artist":"OKRADIO-94.2FM","album":null,"uri":"https://sslstream.okradio.net:80/","trackType":"net:80/"} Jun 18 03:16:50 volumio-home volumio[1061]: info: CURRENT POSITION 0 Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:50 volumio-home volumio[1061]: info: ------------------------------ 138ms Jun 18 03:16:50 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:16:50 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:16:50 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:50 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Mirza Selimovic - Plakaces","artist":"OKRADIO-94.2FM","album":null,"uri":"https://sslstream.okradio.net:80/","trackType":"net:80/"} Jun 18 03:16:50 volumio-home volumio[1061]: info: CURRENT POSITION 0 Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus play Jun 18 03:16:50 volumio-home volumio[1061]: info: Received an update from plugin. extracting info from payload Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:50 volumio-home volumio[1061]: info: ------------------------------ 221ms Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:16:50 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 18 03:16:53 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddQueueItems Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::addQueueItems Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::addQueueItems Jun 18 03:16:54 volumio-home volumio[1061]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::saveQueue Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushQueue Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::updateTrackBlock Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrackBlock Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPlay Jun 18 03:16:54 volumio-home volumio[1061]: UNSET VOLATILE Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::play index 69 Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::stop Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:16:54 volumio-home volumio[1061]: UNSET VOLATILE Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::stPlaybackTimer Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::updateTrackBlock Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrackBlock Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::serviceStop Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 0 Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreCommandRouter::serviceStop Jun 18 03:16:54 volumio-home volumio[1061]: info: [1750216614777] ControllerWebradio::stop Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand stop Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::play index undefined Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:16:54 volumio-home volumio[1061]: info: CoreStateMachine::startPlaybackTimer Jun 18 03:16:54 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:16:54 volumio-home volumio[1061]: info: [1750216614926] ControllerWebradio::clearAddPlayTrack Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand stop Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: Jun 18 03:16:54 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:16:54 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand clear Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: Jun 18 03:16:54 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:16:54 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:16:54 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:54 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:54 volumio-home volumio[1061]: info: Jun 18 03:16:54 volumio-home volumio[1061]: info: ------------------------------ 13ms Jun 18 03:16:54 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:16:54 volumio-home volumio[1061]: info: ControllerMpd::pushError Jun 18 03:16:54 volumio-home volumio[1061]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jun 18 03:16:54 volumio-home volumio[1061]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Jun 18 03:16:54 volumio-home volumio[1061]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 18 03:16:54 volumio-home volumio[1061]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 18 03:16:54 volumio-home volumio[1061]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 18 03:16:54 volumio-home volumio[1061]: info: ------------------------------ 40ms Jun 18 03:16:55 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:55 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 18 03:16:55 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand play Jun 18 03:16:55 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:55 volumio-home volumio[1061]: info: Jun 18 03:16:55 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:16:55 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:16:55 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:55 volumio-home volumio[1061]: info: ------------------------------ 15ms Jun 18 03:16:56 volumio-home volumio[1061]: info: Jun 18 03:16:56 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:16:56 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:56 volumio-home volumio[1061]: info: Jun 18 03:16:56 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:16:56 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:56 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:16:56 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:56 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:56 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:16:56 volumio-home volumio[1061]: info: sending command... Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:16:56 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:16:56 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":117,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1750388298&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUwMzg4Mjk4fX19XX0_&Signature=j9X-YkW905FuwRKlfBMeMMNkFuZpcDMMIUNUAZlfhpN5rirsrm623m6xe5RVMnFIUCQUcZBxwdvBmjZcZCmWnUr-skyLDQ5KsBaeUhRTjSMC1cCtIhEak3kFSbwP8gm5Trm9h3mrXXmzlw54rM6oEhIUd0UtLPLkiqpARD9Im-WiDbhuoq9D4UsFxK3ILEP3pfyG5ArftP3jap6Y7TzIcOfPAwndxkRrhzvNRL4Bd5jWfTPaoGiWn9kQom4DwYEpG0Hb5bLsooHYHKNZA5r4SZM4FcaXThwctw5i~yB5DpW23P7EX7j3N6W1BLVqhcrhfvmyovsN7~dVhsPsqXKHdg__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jun 18 03:16:56 volumio-home volumio[1061]: info: CURRENT POSITION 69 Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:56 volumio-home volumio[1061]: info: ------------------------------ 84ms Jun 18 03:16:56 volumio-home volumio[1061]: info: parsing response... Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:16:56 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:16:56 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:16:56 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1750388298&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUwMzg4Mjk4fX19XX0_&Signature=j9X-YkW905FuwRKlfBMeMMNkFuZpcDMMIUNUAZlfhpN5rirsrm623m6xe5RVMnFIUCQUcZBxwdvBmjZcZCmWnUr-skyLDQ5KsBaeUhRTjSMC1cCtIhEak3kFSbwP8gm5Trm9h3mrXXmzlw54rM6oEhIUd0UtLPLkiqpARD9Im-WiDbhuoq9D4UsFxK3ILEP3pfyG5ArftP3jap6Y7TzIcOfPAwndxkRrhzvNRL4Bd5jWfTPaoGiWn9kQom4DwYEpG0Hb5bLsooHYHKNZA5r4SZM4FcaXThwctw5i~yB5DpW23P7EX7j3N6W1BLVqhcrhfvmyovsN7~dVhsPsqXKHdg__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jun 18 03:16:56 volumio-home volumio[1061]: info: CURRENT POSITION 69 Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus play Jun 18 03:16:56 volumio-home volumio[1061]: info: Received an update from plugin. extracting info from payload Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:16:56 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:16:57 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:16:57 volumio-home volumio[1061]: info: ------------------------------ 167ms Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:16:57 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:16:58 volumio-home volumio[1061]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jun 18 03:16:58 volumio-home volumio[1061]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 18 03:16:58 volumio-home volumio[1061]: Dload Upload Total Spent Left Speed Jun 18 03:16:58 volumio-home volumio[1061]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jun 18 03:16:58 volumio-home volumio[1061]: retrying in 5 seconds, trial 1 Jun 18 03:16:58 volumio-home volumio[1061]: Volumio Calling Home Jun 18 03:17:01 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 18 03:17:01 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddQueueItems Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::addQueueItems Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::addQueueItems Jun 18 03:17:08 volumio-home volumio[1061]: info: Adding Item to queue: http://serpent0.duckdns.org:8088/kbsfm.pls Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::saveQueue Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushQueue Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::updateTrackBlock Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrackBlock Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPlay Jun 18 03:17:08 volumio-home volumio[1061]: UNSET VOLATILE Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::play index 70 Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::stop Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:17:08 volumio-home volumio[1061]: UNSET VOLATILE Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::stPlaybackTimer Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::updateTrackBlock Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrackBlock Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::serviceStop Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 69 Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreCommandRouter::serviceStop Jun 18 03:17:08 volumio-home volumio[1061]: info: [1750216628564] ControllerWebradio::stop Jun 18 03:17:08 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand stop Jun 18 03:17:08 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:08 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::play index undefined Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 70 Jun 18 03:17:08 volumio-home volumio[1061]: info: CoreStateMachine::startPlaybackTimer Jun 18 03:17:08 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 70 Jun 18 03:17:08 volumio-home volumio[1061]: info: [1750216628989] ControllerWebradio::clearAddPlayTrack Jun 18 03:17:08 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand stop Jun 18 03:17:08 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand clear Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:17:09 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand load "http://serpent0.duckdns.org:8088/kbsfm.pls" Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: info: ------------------------------ 12ms Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::pushError Jun 18 03:17:09 volumio-home volumio[1061]: info: message=Cannot read property 'split' of undefined, stack=TypeError: Cannot read property 'split' of undefined Jun 18 03:17:09 volumio-home volumio[1061]: at Promise._successFn (/volumio/app/plugins/music_service/mpd/index.js:290:54) Jun 18 03:17:09 volumio-home volumio[1061]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jun 18 03:17:09 volumio-home volumio[1061]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Jun 18 03:17:09 volumio-home volumio[1061]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 18 03:17:09 volumio-home volumio[1061]: info: ------------------------------ 42ms Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand play Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: ---------------------------- MPD announces system playlist update Jun 18 03:17:09 volumio-home volumio[1061]: info: Ignoring MPD Status Update Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: ------------------------------ 6ms Jun 18 03:17:09 volumio-home volumio[1061]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.444&uuid=1fe7c44ee12fe46cffbd233960d8cc58" http://updates.volumio.org:7070/downloader-v1/track-device Jun 18 03:17:09 volumio-home volumio[1061]: % Total % Received % Xferd Average Speed Time Time Time Current Jun 18 03:17:09 volumio-home volumio[1061]: Dload Upload Total Spent Left Speed Jun 18 03:17:09 volumio-home volumio[1061]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Jun 18 03:17:09 volumio-home volumio[1061]: retrying in 5 seconds, trial 2 Jun 18 03:17:09 volumio-home volumio[1061]: Volumio Calling Home Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: Jun 18 03:17:09 volumio-home volumio[1061]: ---------------------------- MPD announces state update: player Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand status Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseState Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::sendMpdCommand playlistinfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: sending command... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:17:09 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 70 Jun 18 03:17:09 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":255,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1750388298&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUwMzg4Mjk4fX19XX0_&Signature=j9X-YkW905FuwRKlfBMeMMNkFuZpcDMMIUNUAZlfhpN5rirsrm623m6xe5RVMnFIUCQUcZBxwdvBmjZcZCmWnUr-skyLDQ5KsBaeUhRTjSMC1cCtIhEak3kFSbwP8gm5Trm9h3mrXXmzlw54rM6oEhIUd0UtLPLkiqpARD9Im-WiDbhuoq9D4UsFxK3ILEP3pfyG5ArftP3jap6Y7TzIcOfPAwndxkRrhzvNRL4Bd5jWfTPaoGiWn9kQom4DwYEpG0Hb5bLsooHYHKNZA5r4SZM4FcaXThwctw5i~yB5DpW23P7EX7j3N6W1BLVqhcrhfvmyovsN7~dVhsPsqXKHdg__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jun 18 03:17:09 volumio-home volumio[1061]: info: CURRENT POSITION 70 Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus stop Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ------------------------------ 74ms Jun 18 03:17:09 volumio-home volumio[1061]: info: parsing response... Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::parseTrackInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ControllerMpd::pushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::servicePushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState Jun 18 03:17:09 volumio-home volumio[1061]: info: CorePlayQueue::getTrack 70 Jun 18 03:17:09 volumio-home volumio[1061]: info: STATE SERVICE {"status":"play","position":0,"seek":480,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"KBS Classic FM","artist":null,"album":null,"uri":"https://1fm.gscdn.kbs.co.kr/1fm_192_2.m3u8?Expires=1750388298&Policy=eyJTdGF0ZW1lbnQiOlt7IlJlc291cmNlIjoiaHR0cHM6Ly8xZm0uZ3NjZG4ua2JzLmNvLmtyLzFmbV8xOTJfMi5tM3U4IiwiQ29uZGl0aW9uIjp7IkRhdGVMZXNzVGhhbiI6eyJBV1M6RXBvY2hUaW1lIjoxNzUwMzg4Mjk4fX19XX0_&Signature=j9X-YkW905FuwRKlfBMeMMNkFuZpcDMMIUNUAZlfhpN5rirsrm623m6xe5RVMnFIUCQUcZBxwdvBmjZcZCmWnUr-skyLDQ5KsBaeUhRTjSMC1cCtIhEak3kFSbwP8gm5Trm9h3mrXXmzlw54rM6oEhIUd0UtLPLkiqpARD9Im-WiDbhuoq9D4UsFxK3ILEP3pfyG5ArftP3jap6Y7TzIcOfPAwndxkRrhzvNRL4Bd5jWfTPaoGiWn9kQom4DwYEpG0Hb5bLsooHYHKNZA5r4SZM4FcaXThwctw5i~yB5DpW23P7EX7j3N6W1BLVqhcrhfvmyovsN7~dVhsPsqXKHdg__&Key-Pair-Id=APKAICDSGT3Y7IXGJ3TA","trackType":"m3u8"} Jun 18 03:17:09 volumio-home volumio[1061]: info: CURRENT POSITION 70 Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState stateService play Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::syncState currentStatus play Jun 18 03:17:09 volumio-home volumio[1061]: info: Received an update from plugin. extracting info from payload Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::pushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreStateMachine::getState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::volumioPushState Jun 18 03:17:09 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 18 03:17:09 volumio-home volumio[1061]: info: ------------------------------ 157ms Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_KEY" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "API_SECRET" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "username" is not set. Jun 18 03:17:09 volumio-home volumio[1061]: info: [LastFM] configuration error; "authToken" is not set. Jun 18 03:17:17 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-DISCONNECTED bssid=80:ca:4b:37:a4:2f reason=0 locally_generated=1 Jun 18 03:17:17 volumio-home dhcpcd[943]: wlan0: carrier lost Jun 18 03:17:17 volumio-home dhcpcd[943]: eth0: adding route to 192.168.123.0/24 Jun 18 03:17:17 volumio-home dhcpcd[943]: eth0: adding default route via 192.168.123.1 Jun 18 03:17:17 volumio-home dhcpcd[943]: wlan0: deleting host route to 192.168.123.107 via 127.0.0.1 Jun 18 03:17:17 volumio-home dhcpcd[943]: wlan0: deleting route to 192.168.123.0/24 Jun 18 03:17:17 volumio-home dhcpcd[943]: wlan0: deleting host route to 192.168.1.1 Jun 18 03:17:17 volumio-home dhcpcd[943]: wlan0: deleting default route via 192.168.1.1 Jun 18 03:17:17 volumio-home avahi-daemon[566]: Withdrawing address record for 192.168.123.107 on wlan0. Jun 18 03:17:17 volumio-home avahi-daemon[566]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.123.107. Jun 18 03:17:17 volumio-home avahi-daemon[566]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 18 03:17:17 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 18 03:17:17 volumio-home volumio[1061]: info: mDNS: A device disapperared from network Jun 18 03:17:17 volumio-home volumio[1061]: info: mDNS: Device volumio-home disapperared from network Jun 18 03:17:17 volumio-home dhcpcd[943]: eth0: removing route to 192.168.123.0/24 Jun 18 03:17:17 volumio-home dhcpcd[943]: eth0: removing default route via 192.168.123.1 Jun 18 03:17:17 volumio-home wpa_supplicant[947]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Jun 18 03:17:18 volumio-home wpa_supplicant[947]: wlan0: Associated with 80:ca:4b:37:a4:2f Jun 18 03:17:18 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=KR Jun 18 03:17:18 volumio-home wpa_supplicant[947]: wlan0: WPA: Key negotiation completed with 80:ca:4b:37:a4:2f [PTK=CCMP GTK=CCMP] Jun 18 03:17:18 volumio-home wpa_supplicant[947]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:ca:4b:37:a4:2f completed [id=0 id_str=] Jun 18 03:17:18 volumio-home dhcpcd[943]: wlan0: carrier acquired Jun 18 03:17:19 volumio-home ntpd[654]: Deleting interface #4 wlan0, 192.168.123.107#123, interface stats: received=0, sent=0, dropped=0, active_time=65 secs Jun 18 03:17:19 volumio-home ntpd[654]: peers refreshed Jun 18 03:17:22 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jun 18 03:17:25 volumio-home volumio[1061]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Jun 18 03:17:27 volumio-home volumio[1061]: info: CoreCommandRouter::volumioAddQueueItems Jun 18 03:17:27 volumio-home volumio[1061]: info: CoreStateMachine::addQueueItems Jun 18 03:17:27 volumio-home volumio[1061]: info: CorePlayQueue::addQueueItems Jun 18 03:17:27 volumio-home volumio[1061]: info: Adding Item to queue: webkbs/0 Jun 18 03:17:27 volumio-home volumio[1061]: info: Exploding uri webkbs/0 in service personal_radio Jun 18 03:17:27 volumio-home volumio[1061]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 03:17:27 volumio-home volumio[1061]: ReferenceError: self is not defined Jun 18 03:17:27 volumio-home volumio[1061]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Jun 18 03:17:27 volumio-home volumio[1061]: at /data/plugins/music_service/personal_radio/index.js:487:12 Jun 18 03:17:27 volumio-home volumio[1061]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Jun 18 03:17:27 volumio-home volumio[1061]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Jun 18 03:17:27 volumio-home volumio[1061]: at emitOne (events.js:116:13) Jun 18 03:17:27 volumio-home volumio[1061]: at Request.emit (events.js:211:7) Jun 18 03:17:27 volumio-home volumio[1061]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Jun 18 03:17:27 volumio-home volumio[1061]: at emitOne (events.js:116:13) Jun 18 03:17:27 volumio-home volumio[1061]: at ClientRequest.emit (events.js:211:7) Jun 18 03:17:27 volumio-home volumio[1061]: at Socket.socketErrorListener (_http_client.js:387:9) Jun 18 03:17:27 volumio-home volumio[1061]: at emitOne (events.js:116:13) Jun 18 03:17:27 volumio-home volumio[1061]: at Socket.emit (events.js:211:7) Jun 18 03:17:27 volumio-home volumio[1061]: at emitErrorNT (internal/streams/destroy.js:64:8) Jun 18 03:17:27 volumio-home volumio[1061]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Jun 18 03:17:27 volumio-home volumio[1061]: at process._tickCallback (internal/process/next_tick.js:180:9) Jun 18 03:17:27 volumio-home volumio[1061]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 18 03:17:29 volumio-home sudo[1608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-18 03:16 Jun 18 03:17:29 volumio-home sudo[1608]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="3686e5c749983c2b52f621497566574399fc1a6c" VOLUMIO_FE_VERSION="f027ce75738c64189018057558e04cba7a74a080" VOLUMIO_BE_VERSION="00a16140148367a337eb80a05ab0638fd10cd06e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="2018年 08月 13日 星期一 07:01:39 CST" VOLUMIO_VERSION="2.444" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="d41d8cd98f00b204e9800998ecf8427e"