-- Logs begin at Thu 2025-04-24 23:15:35 UTC, end at Thu 2025-04-24 23:17:13 UTC. -- Apr 24 23:16:12 volumio-home systemd[1]: Time has been changed Apr 24 23:16:12 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:12] [info] asio async_connect error: system:111 (Connection refused) Apr 24 23:16:12 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 24 23:16:12 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:12] [error] handle_connect error: Underlying Transport Error Apr 24 23:16:13 volumio-home wireless.js[680]: trying... Apr 24 23:16:13 volumio-home sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 24 23:16:13 volumio-home sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:13 volumio-home sudo[1000]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:13 volumio-home wpa_supplicant[944]: wlan0: Trying to associate with 80:ca:4b:37:a4:2f (SSID='U+NetA42C_5G' freq=5745 MHz) Apr 24 23:16:13 volumio-home wpa_supplicant[944]: wlan0: Associated with 80:ca:4b:37:a4:2f Apr 24 23:16:13 volumio-home wpa_supplicant[944]: wlan0: WPA: Key negotiation completed with 80:ca:4b:37:a4:2f [PTK=CCMP GTK=CCMP] Apr 24 23:16:13 volumio-home wpa_supplicant[944]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:ca:4b:37:a4:2f completed [id=0 id_str=] Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: carrier acquired Apr 24 23:16:13 volumio-home wpa_supplicant[944]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=KR Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: soliciting an IPv6 router Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: using static address 192.168.123.107 Apr 24 23:16:13 volumio-home avahi-daemon[565]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.123.107. Apr 24 23:16:13 volumio-home avahi-daemon[565]: New relevant interface wlan0.IPv4 for mDNS. Apr 24 23:16:13 volumio-home dhcpcd[940]: eth0: adding route to 192.168.123.0/24 Apr 24 23:16:13 volumio-home avahi-daemon[565]: Registering new address record for 192.168.123.107 on wlan0.IPv4. Apr 24 23:16:13 volumio-home dhcpcd[940]: eth0: adding default route via 192.168.123.1 Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: router 192.168.1.1 requires a host route Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: adding host route to 192.168.123.107 via 127.0.0.1 Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: adding route to 192.168.123.0/24 Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: adding host route to 192.168.1.1 Apr 24 23:16:13 volumio-home dhcpcd[940]: wlan0: adding default route via 192.168.1.1 Apr 24 23:16:13 volumio-home dhcpcd[940]: eth0: removing route to 192.168.123.0/24 Apr 24 23:16:13 volumio-home dhcpcd[940]: eth0: removing default route via 192.168.123.1 Apr 24 23:16:14 volumio-home wireless.js[680]: trying... Apr 24 23:16:14 volumio-home sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 24 23:16:14 volumio-home sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:14 volumio-home sudo[1047]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:14 volumio-home wireless.js[680]: Connected to: ----U+NetA42C_5G Apr 24 23:16:14 volumio-home wireless.js[680]: ---- Apr 24 23:16:14 volumio-home wireless.js[680]: ... joined AP, wlan0 IPv4 is 192.168.123.107, ipV6 is undefined Apr 24 23:16:14 volumio-home wireless.js[680]: It's done! AP Apr 24 23:16:14 volumio-home systemd[1]: Started Wireless Services. Apr 24 23:16:14 volumio-home systemd[1]: Starting Volumio Backend Module... Apr 24 23:16:14 volumio-home systemd[1]: Started Volumio Backend Module. Apr 24 23:16:14 volumio-home systemd[1]: Starting Multi-User System. Apr 24 23:16:14 volumio-home systemd[1]: Reached target Multi-User System. Apr 24 23:16:14 volumio-home systemd[1]: Starting Graphical Interface. Apr 24 23:16:14 volumio-home systemd[1]: Reached target Graphical Interface. Apr 24 23:16:14 volumio-home systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 24 23:16:14 volumio-home systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 24 23:16:14 volumio-home systemd[1]: Startup finished in 10.881s (kernel) + 20.359s (userspace) = 31.241s. Apr 24 23:16:15 volumio-home ntpd[653]: Listen normally on 4 wlan0 192.168.123.107 UDP 123 Apr 24 23:16:15 volumio-home ntpd[653]: peers refreshed Apr 24 23:16:18 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:18 volumio-home volumio[1058]: info: ----- Volumio2 ---- Apr 24 23:16:18 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:18 volumio-home volumio[1058]: info: ----- System startup ---- Apr 24 23:16:18 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:19 volumio-home volumio[1058]: info: Plugin folders cleanup Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning into folder /volumio/app/plugins/ Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category audio_interface Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category miscellanea Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category music_service Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category plugins.json Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category system_controller Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category user_interface Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning into folder /data/plugins/ Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category miscellanea Apr 24 23:16:19 volumio-home volumio[1058]: info: Scanning category music_service Apr 24 23:16:19 volumio-home volumio[1058]: info: Plugin folders cleanup completed Apr 24 23:16:19 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:19 volumio-home volumio[1058]: info: ----- Core plugins startup ---- Apr 24 23:16:19 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:19 volumio-home volumio[1058]: info: Loading plugins from folder /volumio/app/plugins/ Apr 24 23:16:19 volumio-home volumio[1058]: info: Loading plugins from folder /data/plugins/ Apr 24 23:16:19 volumio-home volumio[1058]: info: Loading plugin "system"... Apr 24 23:16:19 volumio-home volumio[1058]: info: Loading plugin "appearance"... Apr 24 23:16:21 volumio-home volumio[1058]: info: Loading plugin "network"... Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "services"... Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "alsa_controller"... Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "wizard"... Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "volumio_command_line_client"... Apr 24 23:16:22 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:22 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:22 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:22 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "upnp"... Apr 24 23:16:22 volumio-home volumio[1058]: info: [1745536582460] Starting Upmpd Daemon Apr 24 23:16:22 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "my_music"... Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "mpd"... Apr 24 23:16:22 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:22] [connect] Successful connection Apr 24 23:16:22 volumio-home volumio[1058]: info: Loading plugin "upnp_browser"... Apr 24 23:16:24 volumio-home volumio[1058]: info: Loading plugin "networkfs"... Apr 24 23:16:24 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 23:16:24 volumio-home volumio[1058]: info: Loading plugin "alarm-clock"... Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "airplay_emulation"... Apr 24 23:16:25 volumio-home volumio[1058]: info: [1745536585046] Starting Shairport Sync Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "last_100"... Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "webradio"... Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "i2s_dacs"... Apr 24 23:16:25 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "volumiodiscovery"... Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** For more information see Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 23:16:25 volumio-home node[1058]: *** WARNING *** For more information see Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** For more information see Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 24 23:16:25 volumio-home volumio[1058]: *** WARNING *** For more information see Apr 24 23:16:25 volumio-home volumio[1058]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 24 23:16:25 volumio-home volumio[1058]: Discovery: StartAdv! undefined Apr 24 23:16:25 volumio-home volumio[1058]: Discovery: Started advertising... Volumio-home - undefined Apr 24 23:16:25 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "autostart"... Apr 24 23:16:25 volumio-home volumio[1058]: info: Applying required configuration parameters for plugin autostart Apr 24 23:16:25 volumio-home volumio[1058]: info: Loading plugin "youtube"... Apr 24 23:16:28 volumio-home volumio[1058]: info: Loading plugin "albumart"... Apr 24 23:16:28 volumio-home volumio[1058]: info: Plugin example_plugin is not enabled Apr 24 23:16:28 volumio-home volumio[1058]: info: Loading plugin "updater_comm"... Apr 24 23:16:29 volumio-home volumio[1058]: info: Plugin mpdemulation is not enabled Apr 24 23:16:29 volumio-home volumio[1058]: info: Loading plugin "rest_api"... Apr 24 23:16:29 volumio-home volumio[1058]: info: Loading plugin "websocket"... Apr 24 23:16:29 volumio-home volumio[1058]: info: Loading plugin "lastfm"... Apr 24 23:16:29 volumio-home volumio[1058]: Forking 3 albumart workers Apr 24 23:16:31 volumio-home volumio[1058]: Starting albumart workers Apr 24 23:16:31 volumio-home volumio[1058]: Starting albumart workers Apr 24 23:16:31 volumio-home volumio[1058]: Starting albumart workers Apr 24 23:16:31 volumio-home volumio[1058]: info: [LastFM] scrobbler initiated! Apr 24 23:16:31 volumio-home volumio[1058]: info: [LastFM] extended logging: false Apr 24 23:16:31 volumio-home volumio[1058]: info: [LastFM] try scrobble stream/radio plays: true Apr 24 23:16:31 volumio-home volumio[1058]: info: Loading plugin "minidlna"... Apr 24 23:16:31 volumio-home volumio[1058]: info: Applying required configuration parameters for plugin minidlna Apr 24 23:16:32 volumio-home volumio[1058]: info: Plugin 80s80s is not enabled Apr 24 23:16:32 volumio-home volumio[1058]: info: Loading plugin "personal_radio"... Apr 24 23:16:34 volumio-home volumio[1058]: info: ControllerPersonalRadio::constructor Apr 24 23:16:34 volumio-home volumio[1058]: info: Loading plugin "spop"... Apr 24 23:16:36 volumio-home volumio[1058]: info: ___________ START PLUGINS ___________ Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:36 volumio-home volumio[1058]: info: [1745536596897] CoreMusicLibrary::Adding element Media Servers Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:36 volumio-home volumio[1058]: info: [1745536596937] CoreMusicLibrary::Adding element Last_100 Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:36 volumio-home volumio[1058]: info: [1745536596942] CoreMusicLibrary::Adding element Webradio Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 23:16:36 volumio-home volumio[1058]: info: Youtube::onStart Adding to browse sources Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:36 volumio-home volumio[1058]: info: [1745536596952] CoreMusicLibrary::Adding element Youtube Apr 24 23:16:36 volumio-home volumio[1058]: info: Performing onStart action Apr 24 23:16:36 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:36 volumio-home volumio[1058]: info: [1745536596966] CoreMusicLibrary::Adding element LastFM Apr 24 23:16:36 volumio-home volumio[1058]: info: Loading i18n strings for locale ko Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 24 23:16:37 volumio-home volumio[1058]: info: [1745536597088] CoreMusicLibrary::Adding element 개인 라디오 Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 24 23:16:37 volumio-home volumio[1058]: [SpotifyConnect] Creating VLS config file Apr 24 23:16:37 volumio-home sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: [SpotifyConnect] Starting metadata listener Apr 24 23:16:37 volumio-home sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:37 volumio-home sudo[1108]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:37 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:37 volumio-home volumio[1058]: info: ----- MyVolumio plugins startup ---- Apr 24 23:16:37 volumio-home volumio[1058]: info: ------------------------------------------- Apr 24 23:16:37 volumio-home volumio[1058]: info: Loading plugins from folder /myvolumio/plugins Apr 24 23:16:37 volumio-home volumio[1058]: info: Loading plugins from folder /data/myvolumio/plugins Apr 24 23:16:37 volumio-home volumio[1058]: info: Loading i18n strings for locale ko Apr 24 23:16:37 volumio-home sudo[1117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Apr 24 23:16:37 volumio-home volumio[1058]: Updating browse sources language Apr 24 23:16:37 volumio-home sudo[1117]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:37 volumio-home volumio[1058]: Cannot find translation for sourceMedia Servers Apr 24 23:16:37 volumio-home volumio[1058]: Cannot find translation for sourceYoutube Apr 24 23:16:37 volumio-home volumio[1058]: Cannot find translation for sourceLastFM Apr 24 23:16:37 volumio-home volumio[1058]: Cannot find translation for source개인 라디오 Apr 24 23:16:37 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Apr 24 23:16:37 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Apr 24 23:16:37 volumio-home sudo[1117]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::initPlayerControls Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 24 23:16:37 volumio-home volumio[1058]: info: BOOT COMPLETED Apr 24 23:16:37 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 23:16:37 volumio-home volumio[1122]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 24 23:16:37 volumio-home volumio[1122]: Reading Config from "/tmp/volspotify.toml" Apr 24 23:16:37 volumio-home volumio[1122]: Malformed config key: missing field `Authentication` Apr 24 23:16:37 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:37] [connect] Successful connection Apr 24 23:16:39 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 24 23:16:39 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Apr 24 23:16:39 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Apr 24 23:16:39 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Apr 24 23:16:39 volumio-home volumio[1132]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 24 23:16:39 volumio-home volumio[1132]: Reading Config from "/tmp/volspotify.toml" Apr 24 23:16:39 volumio-home volumio[1132]: Malformed config key: missing field `Authentication` Apr 24 23:16:41 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 24 23:16:41 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Apr 24 23:16:41 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Apr 24 23:16:41 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Apr 24 23:16:41 volumio-home volumio[1135]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 24 23:16:41 volumio-home volumio[1135]: Reading Config from "/tmp/volspotify.toml" Apr 24 23:16:41 volumio-home volumio[1135]: Malformed config key: missing field `Authentication` Apr 24 23:16:42 volumio-home volumio[1058]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreCommandRouter::Close All Modals sent Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreCommandRouter::Close All Modals sent Apr 24 23:16:42 volumio-home volumio[1058]: Express server listening on port 3000 Apr 24 23:16:42 volumio-home volumio[1058]: Volumio Calling Home Apr 24 23:16:42 volumio-home volumio[1058]: info: Starting minidlna.service Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreStateMachine::resetVolumioState Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreStateMachine::getcurrentVolume Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreCommandRouter::volumioRetrievevolume Apr 24 23:16:42 volumio-home sudo[1146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Apr 24 23:16:42 volumio-home sudo[1146]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:42 volumio-home volumio[1058]: info: CoreStateMachine::updateTrackBlock Apr 24 23:16:42 volumio-home volumio[1058]: info: CorePlayQueue::getTrackBlock Apr 24 23:16:43 volumio-home systemd[1]: Starting MiniDLNA UPnP-A/V and DLNA media server... Apr 24 23:16:43 volumio-home systemd[1]: Started MiniDLNA UPnP-A/V and DLNA media server. Apr 24 23:16:43 volumio-home sudo[1146]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:43 volumio-home volumio[1058]: info: Setting Device type: Raspberry PI Apr 24 23:16:43 volumio-home volumio[1058]: [SpotifyConnect] Vollibrespot Daemon service started! Apr 24 23:16:43 volumio-home volumio[1058]: SpotifyConnect: 6251.729ms Apr 24 23:16:43 volumio-home volumio[1058]: info: MPD running with PID568 ,establishing connection Apr 24 23:16:43 volumio-home volumio[1058]: info: VolumeController:: Volume=40 Mute =false Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:43 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:43 volumio-home volumio[1058]: info: start of minidlna.service succeeded. Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 24 23:16:43 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 24 23:16:43 volumio-home volumio[1058]: info: Apr 24 23:16:43 volumio-home volumio[1058]: ---------------------------- Client requests Volume 40 Apr 24 23:16:43 volumio-home volumio[1058]: info: VolumeController::SetAlsaVolume40 Apr 24 23:16:44 volumio-home volumio[1058]: info: Reloading queue from file Apr 24 23:16:44 volumio-home systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Apr 24 23:16:44 volumio-home systemd[1]: Stopping Volspotconnect2 Daemon... Apr 24 23:16:44 volumio-home systemd[1]: Starting Volspotconnect2 Daemon... Apr 24 23:16:44 volumio-home systemd[1]: Started Volspotconnect2 Daemon. Apr 24 23:16:44 volumio-home volumio[1171]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Apr 24 23:16:44 volumio-home volumio[1171]: Reading Config from "/tmp/volspotify.toml" Apr 24 23:16:44 volumio-home volumio[1058]: info: Apr 24 23:16:44 volumio-home volumio[1058]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Apr 24 23:16:44 volumio-home volumio[1058]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Apr 24 23:16:44 volumio-home volumio[1058]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Apr 24 23:16:44 volumio-home volumio[1058]: at emitOne (events.js:116:13) Apr 24 23:16:44 volumio-home volumio[1058]: at Socket.emit (events.js:211:7) Apr 24 23:16:44 volumio-home volumio[1058]: at addChunk (_stream_readable.js:263:12) Apr 24 23:16:44 volumio-home volumio[1058]: at readableAddChunk (_stream_readable.js:246:13) Apr 24 23:16:44 volumio-home volumio[1058]: at Socket.Readable.push (_stream_readable.js:208:10) Apr 24 23:16:44 volumio-home volumio[1058]: at Pipe.onread (net.js:607:20) Apr 24 23:16:44 volumio-home volumio[1171]: [Vollibrespot] : Using Alsa backend with device: plughw:5 Apr 24 23:16:44 volumio-home volumio[1171]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 24 23:16:44 volumio-home volumio[1058]: null Apr 24 23:16:44 volumio-home volumio[1058]: info: VolumeController::Volume 40 Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:44 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:44 volumio-home volumio[1058]: info: Setting volume on startup at 40 Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::setRepeat true single undefined Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:44 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::setRandom false Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:44 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:44 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:44 volumio-home sudo[1175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 24 23:16:44 volumio-home sudo[1175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:44 volumio-home sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Apr 24 23:16:44 volumio-home sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 24 23:16:44 volumio-home systemd[1]: Starting UPnP Renderer front-end to MPD... Apr 24 23:16:44 volumio-home systemd[1]: Started UPnP Renderer front-end to MPD. Apr 24 23:16:44 volumio-home sudo[1175]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:44 volumio-home systemd[1]: Starting ShairportSync AirTunes receiver... Apr 24 23:16:44 volumio-home systemd[1]: Started ShairportSync AirTunes receiver. Apr 24 23:16:44 volumio-home sudo[1177]: pam_unix(sudo:session): session closed for user root Apr 24 23:16:44 volumio-home shairport-sync[1190]: Successful Startup Apr 24 23:16:44 volumio-home volumio[1058]: Discovery: adding 82fd70b1-be72-4c2a-9c12-b70e12e22616 Apr 24 23:16:44 volumio-home volumio[1058]: info: mDNS: Found device Volumio-home Apr 24 23:16:45 volumio-home volumio[1058]: info: [1745536605040] Shairport-Sync Started Apr 24 23:16:45 volumio-home volumio[1058]: Error adding Membership: Error: addMembership EINVAL Apr 24 23:16:45 volumio-home volumio[1058]: info: Upmpdcli Daemon Started Apr 24 23:16:45 volumio-home volumio[1058]: Discovery: this is already registered, 82fd70b1-be72-4c2a-9c12-b70e12e22616 Apr 24 23:16:45 volumio-home volumio[1058]: info: mDNS: Found device Volumio-home Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:45 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:45 volumio-home volumio[1058]: info: AutoStart - getting queue Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreCommandRouter::volumioGetQueue Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreStateMachine::getQueue Apr 24 23:16:45 volumio-home volumio[1058]: info: CorePlayQueue::getQueue Apr 24 23:16:45 volumio-home volumio[1058]: info: AutoStart - start playing -> queue is not empty Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPlay Apr 24 23:16:45 volumio-home volumio[1058]: UNSET VOLATILE Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreStateMachine::play index undefined Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 23:16:45 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:45 volumio-home volumio[1058]: info: CoreStateMachine::startPlaybackTimer Apr 24 23:16:45 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:45 volumio-home volumio[1058]: info: [1745536605730] ControllerWebradio::clearAddPlayTrack Apr 24 23:16:45 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand stop Apr 24 23:16:45 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:45 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:45 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand clear Apr 24 23:16:45 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:45 volumio-home volumio[1058]: info: Apr 24 23:16:45 volumio-home volumio[1058]: ---------------------------- MPD announces system playlist update Apr 24 23:16:45 volumio-home volumio[1058]: info: Ignoring MPD Status Update Apr 24 23:16:45 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:45 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99530397" Apr 24 23:16:45 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:45 volumio-home volumio[1058]: info: Apr 24 23:16:45 volumio-home volumio[1058]: info: ------------------------------ 14ms Apr 24 23:16:46 volumio-home volumio[1058]: info: Apr 24 23:16:46 volumio-home volumio[1058]: ---------------------------- MPD announces system playlist update Apr 24 23:16:46 volumio-home volumio[1058]: info: Ignoring MPD Status Update Apr 24 23:16:46 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreStateMachine::setConsumeUpdateService mpd Apr 24 23:16:46 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand play Apr 24 23:16:46 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:46 volumio-home volumio[1058]: info: ------------------------------ 15ms Apr 24 23:16:46 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreCommandRouter::volumioGetState Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreCommandRouter::volumioGetBrowseSources Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 24 23:16:46 volumio-home volumio[1058]: Plugin multiroom or method getMultiroom not found Apr 24 23:16:46 volumio-home volumio[1058]: info: Listing playlists Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreCommandRouter::volumioGetQueue Apr 24 23:16:46 volumio-home volumio[1058]: info: CoreStateMachine::getQueue Apr 24 23:16:46 volumio-home volumio[1058]: info: CorePlayQueue::getQueue Apr 24 23:16:47 volumio-home volumio[1058]: info: Checking Spotify Web API Apr 24 23:16:52 volumio-home volumio[1058]: info: Apr 24 23:16:52 volumio-home volumio[1058]: ---------------------------- MPD announces state update: player Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::getState Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand status Apr 24 23:16:52 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:52 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::parseState Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 23:16:52 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:52 volumio-home volumio[1058]: info: Apr 24 23:16:52 volumio-home volumio[1058]: ---------------------------- MPD announces system playlist update Apr 24 23:16:52 volumio-home volumio[1058]: info: Ignoring MPD Status Update Apr 24 23:16:52 volumio-home volumio[1058]: info: Apr 24 23:16:52 volumio-home volumio[1058]: ---------------------------- MPD announces state update: player Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::getState Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand status Apr 24 23:16:52 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:52 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::parseTrackInfo Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::pushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::servicePushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState Apr 24 23:16:52 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:52 volumio-home volumio[1058]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Andreana Cekic - Party Party 2024","artist":"OKRADIO-94.2FM","album":null,"uri":"https://sslstream.okradio.net:80/","trackType":"net:80/"} Apr 24 23:16:52 volumio-home volumio[1058]: info: CURRENT POSITION 0 Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState currentStatus stop Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:52 volumio-home volumio[1058]: info: ------------------------------ 94ms Apr 24 23:16:52 volumio-home volumio[1058]: info: ------------------------------ 74ms Apr 24 23:16:52 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::parseState Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 23:16:52 volumio-home volumio[1058]: info: sending command... Apr 24 23:16:52 volumio-home volumio[1058]: info: parsing response... Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::parseTrackInfo Apr 24 23:16:52 volumio-home volumio[1058]: info: ControllerMpd::pushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::servicePushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState Apr 24 23:16:52 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:16:52 volumio-home volumio[1058]: 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":"Andreana Cekic - Party Party 2024","artist":"OKRADIO-94.2FM","album":null,"uri":"https://sslstream.okradio.net:80/","trackType":"net:80/"} Apr 24 23:16:52 volumio-home volumio[1058]: info: CURRENT POSITION 0 Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 24 23:16:52 volumio-home volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:16:52 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:16:52 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:52] [connect] Successful connection Apr 24 23:16:52 volumio-home volumio[1058]: info: ------------------------------ 190ms Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_KEY" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_SECRET" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "username" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "authToken" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_KEY" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_SECRET" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "username" is not set. Apr 24 23:16:52 volumio-home volumio[1058]: info: [LastFM] configuration error; "authToken" is not set. Apr 24 23:16:52 volumio-home volumio-remote-updater[535]: [2025-04-24 23:16:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1745536612 101 Apr 24 23:16:53 volumio-home volumio[1058]: 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 Apr 24 23:16:53 volumio-home volumio[1058]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 23:16:53 volumio-home volumio[1058]: Dload Upload Total Spent Left Speed Apr 24 23:16:53 volumio-home volumio[1058]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 24 23:16:53 volumio-home volumio[1058]: retrying in 5 seconds, trial 0 Apr 24 23:16:53 volumio-home volumio[1058]: Volumio Calling Home Apr 24 23:17:04 volumio-home volumio[1058]: 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 Apr 24 23:17:04 volumio-home volumio[1058]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 24 23:17:04 volumio-home volumio[1058]: Dload Upload Total Spent Left Speed Apr 24 23:17:04 volumio-home volumio[1058]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Apr 24 23:17:04 volumio-home volumio[1058]: retrying in 5 seconds, trial 1 Apr 24 23:17:04 volumio-home volumio[1058]: Volumio Calling Home Apr 24 23:17:06 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 24 23:17:06 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 24 23:17:07 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 24 23:17:08 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: personal_radio , handleBrowseUri Apr 24 23:17:09 volumio-home volumio[1058]: info: Apr 24 23:17:09 volumio-home volumio[1058]: ---------------------------- MPD announces system playlist update Apr 24 23:17:09 volumio-home volumio[1058]: info: Ignoring MPD Status Update Apr 24 23:17:09 volumio-home volumio[1058]: info: Apr 24 23:17:09 volumio-home volumio[1058]: ---------------------------- MPD announces state update: player Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::getState Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand status Apr 24 23:17:09 volumio-home volumio[1058]: info: sending command... Apr 24 23:17:09 volumio-home volumio[1058]: info: ------------------------------ 9ms Apr 24 23:17:09 volumio-home volumio[1058]: info: parsing response... Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::parseState Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::sendMpdCommand playlistinfo Apr 24 23:17:09 volumio-home volumio[1058]: info: sending command... Apr 24 23:17:09 volumio-home volumio[1058]: info: parsing response... Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::parseTrackInfo Apr 24 23:17:09 volumio-home volumio[1058]: info: ControllerMpd::pushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::servicePushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::syncState Apr 24 23:17:09 volumio-home volumio[1058]: info: CorePlayQueue::getTrack 0 Apr 24 23:17:09 volumio-home volumio[1058]: info: STATE SERVICE {"status":"play","position":0,"seek":17345,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"Allegro Band - Vatra i kisa","artist":"OKRADIO-94.2FM","album":null,"uri":"https://sslstream.okradio.net:80/","trackType":"net:80/"} Apr 24 23:17:09 volumio-home volumio[1058]: info: CURRENT POSITION 0 Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::syncState stateService play Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::syncState currentStatus play Apr 24 23:17:09 volumio-home volumio[1058]: info: Received an update from plugin. extracting info from payload Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::pushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreStateMachine::getState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::volumioPushState Apr 24 23:17:09 volumio-home volumio[1058]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 23:17:09 volumio-home volumio[1058]: info: ------------------------------ 109ms Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_KEY" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_SECRET" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "username" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "authToken" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_KEY" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "API_SECRET" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "username" is not set. Apr 24 23:17:09 volumio-home volumio[1058]: info: [LastFM] configuration error; "authToken" is not set. Apr 24 23:17:11 volumio-home volumio[1058]: info: CoreCommandRouter::volumioAddQueueItems Apr 24 23:17:11 volumio-home volumio[1058]: info: CoreStateMachine::addQueueItems Apr 24 23:17:11 volumio-home volumio[1058]: info: CorePlayQueue::addQueueItems Apr 24 23:17:11 volumio-home volumio[1058]: info: Adding Item to queue: webkbs/0 Apr 24 23:17:11 volumio-home volumio[1058]: info: Exploding uri webkbs/0 in service personal_radio Apr 24 23:17:12 volumio-home volumio[1058]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 23:17:12 volumio-home volumio[1058]: ReferenceError: self is not defined Apr 24 23:17:12 volumio-home volumio[1058]: at ControllerPersonalRadio.errorToast (/data/plugins/music_service/personal_radio/index.js:575:22) Apr 24 23:17:12 volumio-home volumio[1058]: at /data/plugins/music_service/personal_radio/index.js:487:12 Apr 24 23:17:12 volumio-home volumio[1058]: at Request.handleRequestResponse [as _callback] (/data/plugins/music_service/personal_radio/node_modules/unirest/index.js:355:15) Apr 24 23:17:12 volumio-home volumio[1058]: at self.callback (/data/plugins/music_service/personal_radio/node_modules/request/request.js:187:22) Apr 24 23:17:12 volumio-home volumio[1058]: at emitOne (events.js:116:13) Apr 24 23:17:12 volumio-home volumio[1058]: at Request.emit (events.js:211:7) Apr 24 23:17:12 volumio-home volumio[1058]: at Request.onRequestError (/data/plugins/music_service/personal_radio/node_modules/request/request.js:813:8) Apr 24 23:17:12 volumio-home volumio[1058]: at emitOne (events.js:116:13) Apr 24 23:17:12 volumio-home volumio[1058]: at ClientRequest.emit (events.js:211:7) Apr 24 23:17:12 volumio-home volumio[1058]: at Socket.socketErrorListener (_http_client.js:387:9) Apr 24 23:17:12 volumio-home volumio[1058]: at emitOne (events.js:116:13) Apr 24 23:17:12 volumio-home volumio[1058]: at Socket.emit (events.js:211:7) Apr 24 23:17:12 volumio-home volumio[1058]: at emitErrorNT (internal/streams/destroy.js:64:8) Apr 24 23:17:12 volumio-home volumio[1058]: at _combinedTickCallback (internal/process/next_tick.js:138:11) Apr 24 23:17:12 volumio-home volumio[1058]: at process._tickCallback (internal/process/next_tick.js:180:9) Apr 24 23:17:12 volumio-home volumio[1058]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 23:17:13 volumio-home sudo[1433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 23:16 Apr 24 23:17:13 volumio-home sudo[1433]: 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"