-- Logs begin at Sat 2026-10-17 02:45:35 UTC, end at Sat 2026-10-17 02:47:20 UTC. -- Oct 17 02:46:00 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:46:00 volumio wireless.js[761]: trying... Oct 17 02:46:00 volumio sudo[1039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:00 volumio sudo[1039]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:00 volumio sudo[1039]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:00 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:46:00 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="F4239C707689-2G" auth_failures=1 duration=10 reason=CONN_FAILED Oct 17 02:46:25 volumio systemd[1]: Time has been changed Oct 17 02:46:25 volumio wireless.js[761]: trying... Oct 17 02:46:25 volumio sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:25 volumio sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:25 volumio sudo[1048]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:26 volumio wireless.js[761]: trying... Oct 17 02:46:26 volumio sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:26 volumio sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:27 volumio sudo[1057]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:28 volumio wireless.js[761]: trying... Oct 17 02:46:28 volumio sudo[1066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:28 volumio sudo[1066]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:28 volumio sudo[1066]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:29 volumio wireless.js[761]: trying... Oct 17 02:46:29 volumio sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:29 volumio sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:29 volumio sudo[1075]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:30 volumio volumio-remote-updater[619]: [2026-10-17 02:46:30] [info] asio async_connect error: system:111 (Connection refused) Oct 17 02:46:30 volumio volumio-remote-updater[619]: [2026-10-17 02:46:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 17 02:46:30 volumio volumio-remote-updater[619]: [2026-10-17 02:46:30] [error] handle_connect error: Underlying Transport Error Oct 17 02:46:30 volumio wireless.js[761]: trying... Oct 17 02:46:30 volumio sudo[1084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:30 volumio sudo[1084]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:30 volumio sudo[1084]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:31 volumio wireless.js[761]: trying... Oct 17 02:46:31 volumio sudo[1093]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:31 volumio sudo[1093]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:31 volumio sudo[1093]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:31 volumio kernel: Voltage normalised (0x00000000) Oct 17 02:46:32 volumio wireless.js[761]: trying... Oct 17 02:46:32 volumio sudo[1102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:32 volumio sudo[1102]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:32 volumio sudo[1102]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:33 volumio wireless.js[761]: trying... Oct 17 02:46:33 volumio sudo[1111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:33 volumio sudo[1111]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:33 volumio sudo[1111]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:34 volumio wireless.js[761]: trying... Oct 17 02:46:34 volumio sudo[1120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Oct 17 02:46:34 volumio sudo[1120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:34 volumio sudo[1120]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:35 volumio wireless.js[761]: Overtime, starting plan B Oct 17 02:46:35 volumio systemd[1]: Started Wireless Services. Oct 17 02:46:35 volumio systemd[1]: Starting Volumio Backend Module... Oct 17 02:46:35 volumio systemd[1]: Started Volumio Backend Module. Oct 17 02:46:35 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 17 02:46:35 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 17 02:46:35 volumio systemd[1]: Starting Multi-User System. Oct 17 02:46:35 volumio systemd[1]: Reached target Multi-User System. Oct 17 02:46:35 volumio systemd[1]: Starting Graphical Interface. Oct 17 02:46:35 volumio systemd[1]: Reached target Graphical Interface. Oct 17 02:46:35 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 17 02:46:35 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 17 02:46:35 volumio systemd[1]: Startup finished in 11.647s (kernel) + 36.906s (userspace) = 48.553s. Oct 17 02:46:35 volumio kernel: Under-voltage detected! (0x00050005) Oct 17 02:46:35 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="F4239C707689-2G" Oct 17 02:46:35 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:46:35 volumio volumio-streaming-daemon[1130]: ############################ Oct 17 02:46:35 volumio volumio-streaming-daemon[1130]: # Volumio Streaming Daemon # Oct 17 02:46:35 volumio volumio-streaming-daemon[1130]: # Running on port 7777 # Oct 17 02:46:35 volumio volumio-streaming-daemon[1130]: ############################ Oct 17 02:46:35 volumio volumio-streaming-daemon[1130]: Environment: production Oct 17 02:46:36 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:46:36 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="F4239C707689-2G" auth_failures=2 duration=23 reason=CONN_FAILED Oct 17 02:46:40 volumio volumio-remote-updater[619]: [2026-10-17 02:46:40] [connect] Successful connection Oct 17 02:46:40 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:40 volumio volumio[1129]: info: ----- Volumio2 ---- Oct 17 02:46:40 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:40 volumio volumio[1129]: info: ----- System startup ---- Oct 17 02:46:40 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:42 volumio volumio[1129]: info: MYVOLUMIO Environment detected Oct 17 02:46:42 volumio volumio[1129]: info: Plugin folders cleanup Oct 17 02:46:42 volumio volumio[1129]: info: Scanning into folder /volumio/app/plugins/ Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category audio_interface Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category miscellanea Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category music_service Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category plugins.json Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category system_controller Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category user_interface Oct 17 02:46:42 volumio volumio[1129]: info: Scanning into folder /data/plugins/ Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category miscellanea Oct 17 02:46:42 volumio volumio[1129]: info: Scanning category music_service Oct 17 02:46:42 volumio volumio[1129]: info: Plugin folders cleanup completed Oct 17 02:46:42 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:42 volumio volumio[1129]: info: ----- Core plugins startup ---- Oct 17 02:46:42 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:42 volumio volumio[1129]: info: Loading plugins from folder /volumio/app/plugins/ Oct 17 02:46:42 volumio volumio[1129]: info: Adding plugin upnp to MyMusic Plugins Oct 17 02:46:42 volumio volumio[1129]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 17 02:46:42 volumio volumio[1129]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 17 02:46:42 volumio volumio[1129]: info: Loading plugins from folder /data/plugins/ Oct 17 02:46:42 volumio volumio[1129]: info: Loading plugin "system"... Oct 17 02:46:42 volumio volumio[1129]: info: Loading plugin "appearance"... Oct 17 02:46:45 volumio volumio[1129]: info: Loading plugin "network"... Oct 17 02:46:45 volumio volumio[1129]: info: Refreshing Cached IP Addresses Oct 17 02:46:45 volumio sudo[1158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 02:46:45 volumio volumio[1129]: info: Loading plugin "touch_display"... Oct 17 02:46:45 volumio sudo[1158]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:45 volumio sudo[1160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 02:46:45 volumio sudo[1158]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:45 volumio sudo[1160]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:45 volumio sudo[1160]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:46 volumio volumio[1129]: info: Applying required configuration parameters for plugin touch_display Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "services"... Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "alsa_controller"... Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "wizard"... Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "volumio_command_line_client"... Oct 17 02:46:46 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="F4239C707689-2G" Oct 17 02:46:46 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "upnp"... Oct 17 02:46:46 volumio volumio[1129]: info: [1792205206759] Starting Upmpd Daemon Oct 17 02:46:46 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "my_music"... Oct 17 02:46:46 volumio volumio[1129]: info: Loading plugin "mpd"... Oct 17 02:46:47 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:46:47 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="F4239C707689-2G" auth_failures=2 duration=26 reason=CONN_FAILED Oct 17 02:46:47 volumio volumio[1129]: info: Loading plugin "upnp_browser"... Oct 17 02:46:48 volumio volumio[1129]: info: Loading plugin "networkfs"... Oct 17 02:46:49 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 02:46:49 volumio volumio[1129]: info: Loading plugin "alarm-clock"... Oct 17 02:46:49 volumio sudo[1184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=slynir@gmail.com,password=osashimi0224,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft,vers=3.0 //192.168.3.60/music /mnt/NAS/music Oct 17 02:46:49 volumio sudo[1184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:49 volumio kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 17 02:46:49 volumio kernel: Key type cifs.spnego registered Oct 17 02:46:49 volumio kernel: Key type cifs.idmap registered Oct 17 02:46:49 volumio sudo[1184]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:49 volumio volumio[1129]: info: Loading plugin "airplay_emulation"... Oct 17 02:46:49 volumio volumio[1129]: info: Starting Shairport Sync Oct 17 02:46:49 volumio volumio[1129]: info: Loading plugin "last_100"... Oct 17 02:46:49 volumio volumio[1129]: info: Loading plugin "webradio"... Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "i2s_dacs"... Oct 17 02:46:50 volumio volumio[1129]: info: I2S DAC not set, start Auto-detection Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "volumiodiscovery"... Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 02:46:50 volumio node[1129]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 17 02:46:50 volumio node[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 02:46:50 volumio node[1129]: *** WARNING *** For more information see Oct 17 02:46:50 volumio node[1129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 02:46:50 volumio node[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 02:46:50 volumio node[1129]: *** WARNING *** For more information see Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** For more information see Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 17 02:46:50 volumio volumio[1129]: *** WARNING *** For more information see Oct 17 02:46:50 volumio volumio[1129]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 17 02:46:50 volumio volumio[1129]: Discovery: StartAdv! undefined Oct 17 02:46:50 volumio volumio[1129]: Discovery: Started advertising... Volumio - undefined Oct 17 02:46:50 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "outputs"... Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "albumart"... Oct 17 02:46:50 volumio volumio[1129]: info: Plugin example_plugin is not enabled Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "inputs"... Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "updater_comm"... Oct 17 02:46:50 volumio volumio[1129]: info: Plugin mpdemulation is not enabled Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "rest_api"... Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "websocket"... Oct 17 02:46:50 volumio volumio[1129]: info: Loading plugin "volspotconnect2"... Oct 17 02:46:51 volumio volumio[1129]: Forking 3 albumart workers Oct 17 02:46:52 volumio volumio[1129]: Starting albumart workers Oct 17 02:46:52 volumio volumio[1129]: Starting albumart workers Oct 17 02:46:52 volumio volumio[1129]: Starting albumart workers Oct 17 02:46:53 volumio volumio[1129]: info: ___________ START PLUGINS ___________ Oct 17 02:46:53 volumio volumio[1129]: info: Loading i18n strings for locale ja Oct 17 02:46:53 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 02:46:53 volumio volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 02:46:53 volumio volumio[1129]: info: [1792205213821] CoreMusicLibrary::Adding element Media Servers Oct 17 02:46:53 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 02:46:53 volumio sudo[1270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 17 02:46:53 volumio sudo[1270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:53 volumio systemd[1]: Reloading. Oct 17 02:46:54 volumio volumio[1129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 02:46:54 volumio systemd[1]: [/lib/systemd/system/mpd.service:18] Unknown lvalue 'ProtectKernelTunables' in section 'Service' Oct 17 02:46:54 volumio systemd[1]: [/lib/systemd/system/mpd.service:19] Unknown lvalue 'ProtectControlGroups' in section 'Service' Oct 17 02:46:54 volumio systemd[1]: [/lib/systemd/system/mpd.service:20] Unknown lvalue 'ProtectKernelModules' in section 'Service' Oct 17 02:46:54 volumio systemd[1]: [/lib/systemd/system/mpd.service:23] Unknown lvalue 'RestrictNamespaces' in section 'Service' Oct 17 02:46:54 volumio volumio[1129]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 02:46:54 volumio volumio[1129]: info: [1792205214166] CoreMusicLibrary::Adding element Last_100 Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 17 02:46:54 volumio volumio[1129]: info: [1792205214171] CoreMusicLibrary::Adding element Webradio Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 17 02:46:54 volumio volumio[1129]: [SpotifyConnect] Creating VLS config file Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: [SpotifyConnect] Starting metadata listener Oct 17 02:46:54 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:54 volumio volumio[1129]: info: ----- MyVolumio plugins startup ---- Oct 17 02:46:54 volumio volumio[1129]: info: ------------------------------------------- Oct 17 02:46:54 volumio volumio[1129]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 17 02:46:54 volumio volumio[1129]: info: Loading i18n strings for locale ja Oct 17 02:46:54 volumio sudo[1270]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:54 volumio volumio[1129]: Updating browse sources language Oct 17 02:46:54 volumio volumio[1129]: Cannot find translation for sourceMedia Servers Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 02:46:54 volumio sudo[1309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect2.service Oct 17 02:46:54 volumio sudo[1309]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::initPlayerControls Oct 17 02:46:54 volumio systemd[1]: Starting Volspotconnect2 Daemon... Oct 17 02:46:54 volumio systemd[1]: Started Volspotconnect2 Daemon. Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 17 02:46:54 volumio volumio[1129]: info: BOOT COMPLETED Oct 17 02:46:54 volumio volumio[1129]: [Metrics] CommandRouter: 14s 199.98ms Oct 17 02:46:54 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:46:54 volumio volumio[1317]: vollibrespot v0.2.2 cd96399 2020-10-07 (librespot 08d8bcc 2020-10-07) -- Built On 2020-10-07 Oct 17 02:46:54 volumio volumio[1317]: Reading Config from "volspotify.toml" Oct 17 02:46:54 volumio volumio[1317]: [Vollibrespot] : Using Alsa backend with device: plughw:1 Oct 17 02:46:54 volumio sudo[1309]: pam_unix(sudo:session): session closed for user root Oct 17 02:46:54 volumio volumio[1317]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Other, message: "No such device" } Oct 17 02:46:55 volumio volumio-remote-updater[619]: [2026-10-17 02:46:55] [connect] Successful connection Oct 17 02:46:57 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:46:58 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:46:58 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=2 ssid="F4239C707689-2G" auth_failures=1 duration=10 reason=CONN_FAILED Oct 17 02:46:59 volumio volumio[1129]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Oct 17 02:46:59 volumio volumio[1129]: info: CoreCommandRouter::Close All Modals sent Oct 17 02:46:59 volumio volumio[1129]: info: CoreCommandRouter::Close All Modals sent Oct 17 02:46:59 volumio volumio[1129]: Express server listening on port 3000 Oct 17 02:46:59 volumio volumio[1129]: [Metrics] WebUI: 20s 789.71ms Oct 17 02:46:59 volumio volumio[1129]: Volumio Calling Home Oct 17 02:47:00 volumio volumio[1129]: info: CoreStateMachine::resetVolumioState Oct 17 02:47:00 volumio volumio[1129]: info: CoreStateMachine::getcurrentVolume Oct 17 02:47:00 volumio volumio[1129]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 02:47:00 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:47:00 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 17 02:47:00 volumio sudo[1334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 17 02:47:00 volumio sudo[1334]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:00 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 17 02:47:00 volumio sudo[1334]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:00 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:00 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:00 volumio volumio[1129]: info: Setting Device type: Raspberry PI Oct 17 02:47:00 volumio volumio[1129]: [SpotifyConnect] Vollibrespot Daemon service started! Oct 17 02:47:00 volumio volumio[1129]: [Metrics] SpotifyConnect: 6s 715.88ms Oct 17 02:47:00 volumio volumio[1129]: info: touch_display: systemctl daemon-reload succeeded. Oct 17 02:47:00 volumio volumio[1129]: error: Cannot initialize MPD Connection: MPD is not running Oct 17 02:47:00 volumio sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Oct 17 02:47:00 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 17 02:47:00 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 17 02:47:00 volumio sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:00 volumio sudo[1355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 17 02:47:00 volumio sudo[1355]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:01 volumio systemd[1]: Starting Volumio Kiosk... Oct 17 02:47:01 volumio systemd[1]: Started Volumio Kiosk. Oct 17 02:47:01 volumio sudo[1353]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:01 volumio sudo[1355]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 17 02:47:01 volumio startx[1366]: X.Org X Server 1.18.4 Oct 17 02:47:01 volumio startx[1366]: Release Date: 2016-07-19 Oct 17 02:47:01 volumio startx[1366]: X Protocol Version 11, Revision 0 Oct 17 02:47:01 volumio startx[1366]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Oct 17 02:47:01 volumio startx[1366]: Current Operating System: Linux volumio 4.19.118-v7+ #1311 SMP Mon Apr 27 14:21:24 BST 2020 armv7l Oct 17 02:47:01 volumio startx[1366]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 bcm2708_fb.fbwidth=1184 bcm2708_fb.fbheight=624 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 snd-bcm2835.enable_compat_alsa=1 snd_bcm2835.enable_headphones=1 Oct 17 02:47:01 volumio startx[1366]: Build Date: 11 November 2016 11:59:59AM Oct 17 02:47:01 volumio startx[1366]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Oct 17 02:47:01 volumio startx[1366]: Current version of pixman: 0.33.3 Oct 17 02:47:01 volumio startx[1366]: Before reporting problems, check http://wiki.x.org Oct 17 02:47:01 volumio startx[1366]: to make sure that you have the latest version. Oct 17 02:47:01 volumio startx[1366]: Markers: (--) probed, (**) from config file, (==) default setting, Oct 17 02:47:01 volumio startx[1366]: (++) from command line, (!!) notice, (II) informational, Oct 17 02:47:01 volumio startx[1366]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Oct 17 02:47:01 volumio startx[1366]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Oct 17 02:47:01 2026 Oct 17 02:47:01 volumio startx[1366]: (==) Using config directory: "/etc/X11/xorg.conf.d" Oct 17 02:47:01 volumio startx[1366]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Oct 17 02:47:01 volumio volumio[1129]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Oct 17 02:47:01 volumio volumio[1129]: info: touch_display: Volumio Kiosk started Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:01 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:01 volumio volumio[1129]: info: VolumeController:: Volume=46 Mute =false Oct 17 02:47:01 volumio volumio[1129]: info: CoreStateMachine::pushState Oct 17 02:47:01 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::volumioPushState Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 02:47:01 volumio volumio[1129]: info: CoreStateMachine::updateTrackBlock Oct 17 02:47:01 volumio volumio[1129]: info: CorePlayQueue::getTrackBlock Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::volumioRetrievevolume Oct 17 02:47:01 volumio startx[1366]: modprobe: FATAL: Module g2d_23 not found. Oct 17 02:47:01 volumio volumio[1129]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 17 02:47:01 volumio volumio[1129]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Oct 17 02:47:01 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:47:01 volumio volumio[1129]: info: Reloading queue from file Oct 17 02:47:01 volumio sudo[1401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e /Option "TransformationMatrix"/d /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Oct 17 02:47:01 volumio sudo[1401]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:01 volumio sudo[1401]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:02 volumio volumio[1129]: info: CoreStateMachine::setRepeat true single undefined Oct 17 02:47:02 volumio volumio[1129]: info: CoreStateMachine::pushState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioPushState Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 02:47:02 volumio volumio[1129]: info: CoreStateMachine::setRandom null Oct 17 02:47:02 volumio volumio[1129]: info: CoreStateMachine::pushState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioPushState Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio volumio[1129]: info: touch_display: Touchscreen transformation matrix removed. Oct 17 02:47:02 volumio volumio[1129]: info: VolumeController:: Volume=46 Mute =false Oct 17 02:47:02 volumio volumio[1129]: info: CoreStateMachine::pushState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioPushState Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 17 02:47:02 volumio volumio[1129]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Oct 17 02:47:02 volumio volumio[1129]: info: Starting Shairport Sync Oct 17 02:47:02 volumio volumio[1129]: info: Starting Shairport Sync Oct 17 02:47:02 volumio sudo[1418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 02:47:02 volumio sudo[1420]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 17 02:47:02 volumio sudo[1418]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:02 volumio sudo[1420]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:02 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 17 02:47:02 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:02 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 17 02:47:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 02:47:02 volumio sudo[1418]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:02 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 17 02:47:02 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Oct 17 02:47:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 17 02:47:02 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:02 volumio volumio[1129]: Discovery: adding 88504f99-b562-40ef-a274-6c3954ce67b9 Oct 17 02:47:02 volumio volumio[1129]: info: mDNS: Found device Volumio Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 17 02:47:02 volumio sudo[1420]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 17 02:47:02 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:02 volumio volumio[1129]: info: Shairport-Sync Started Oct 17 02:47:02 volumio volumio[1129]: Error adding Membership: Error: addMembership EINVAL Oct 17 02:47:02 volumio volumio[1129]: info: Upmpdcli Daemon Started Oct 17 02:47:02 volumio volumio[1129]: info: Shairport-Sync Started Oct 17 02:47:02 volumio mpd[1457]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:02 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Oct 17 02:47:02 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:02 volumio systemd[1]: Unit mpd.service entered failed state. Oct 17 02:47:02 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:02 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:02 volumio mpd[1459]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Oct 17 02:47:03 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Oct 17 02:47:03 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:03 volumio systemd[1]: Unit mpd.service entered failed state. Oct 17 02:47:03 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:03 volumio mpd[1461]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Oct 17 02:47:03 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Oct 17 02:47:03 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:03 volumio systemd[1]: Unit mpd.service entered failed state. Oct 17 02:47:03 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:03 volumio mpd[1463]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:03 volumio systemd[1]: Unit mpd.service entered failed state. Oct 17 02:47:03 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:03 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 17 02:47:03 volumio volumio[1129]: info: Listing playlists Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::volumioGetVisibleSources Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 17 02:47:03 volumio volumio[1129]: info: CoreCommandRouter::volumioGetQueue Oct 17 02:47:03 volumio volumio[1129]: info: CoreStateMachine::getQueue Oct 17 02:47:03 volumio volumio[1129]: info: CorePlayQueue::getQueue Oct 17 02:47:03 volumio mpd[1465]: /usr/bin/mpd: error while loading shared libraries: libbcm_host.so: cannot open shared object file: No such file or directory Oct 17 02:47:03 volumio systemd[1]: mpd.service: main process exited, code=exited, status=127/n/a Oct 17 02:47:03 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:03 volumio systemd[1]: Unit mpd.service entered failed state. Oct 17 02:47:03 volumio systemd[1]: Starting Music Player Daemon... Oct 17 02:47:03 volumio systemd[1]: mpd.service start request repeated too quickly, refusing to start. Oct 17 02:47:03 volumio systemd[1]: Failed to start Music Player Daemon. Oct 17 02:47:03 volumio systemd[1]: Unit mpd.socket entered failed state. Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 17 02:47:03 volumio volumio[1129]: info: Adding plugin bluetooth to MyMusic Plugins Oct 17 02:47:03 volumio volumio[1129]: info: Adding plugin cd_controller to MyMusic Plugins Oct 17 02:47:03 volumio volumio[1129]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 17 02:47:03 volumio volumio[1129]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 17 02:47:04 volumio startx[1366]: modprobe: FATAL: Module g2d_23 not found. Oct 17 02:47:07 volumio dbus[687]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' Oct 17 02:47:07 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 17 02:47:07 volumio dbus[687]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 17 02:47:07 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Successfully called chroot. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Successfully dropped privileges. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Successfully limited resources. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Running. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Canary thread running. Oct 17 02:47:07 volumio rtkit-daemon[1547]: Watchdog thread running. Oct 17 02:47:08 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="F4239C707689-2G" Oct 17 02:47:08 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:47:09 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:47:09 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="F4239C707689-2G" auth_failures=3 duration=57 reason=CONN_FAILED Oct 17 02:47:10 volumio volumio-remote-updater[619]: [2026-10-17 02:47:10] [connect] Successful connection Oct 17 02:47:14 volumio pulseaudio[1546]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Oct 17 02:47:14 volumio pulseaudio[1546]: Unable to contact D-Bus: org.freedesktop.DBus.Error.Spawn.ExecFailed: /usr/bin/dbus-launch terminated abnormally without any error message Oct 17 02:47:14 volumio pulseaudio[1546]: org.bluez.Manager.GetProperties() failed: org.freedesktop.DBus.Error.UnknownMethod: Method "GetProperties" with signature "" on interface "org.bluez.Manager" doesn't exist Oct 17 02:47:14 volumio bluetoothd[818]: Endpoint registered: sender=:1.19 path=/MediaEndpoint/A2DPSource Oct 17 02:47:14 volumio bluetoothd[818]: Endpoint registered: sender=:1.19 path=/MediaEndpoint/A2DPSink Oct 17 02:47:14 volumio volumio[1129]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 17 02:47:17 volumio volumio[1129]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 17 02:47:17 volumio volumio[1129]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 17 02:47:17 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:47:17 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 17 02:47:17 volumio volumio[1129]: info: Starting MyVolumio Remote Streaming Endpoints Oct 17 02:47:17 volumio volumio[1129]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 17 02:47:17 volumio volumio[1129]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 17 02:47:17 volumio volumio[1129]: info: Streaming services startup Oct 17 02:47:17 volumio volumio[1129]: info: Starting Streaming Daemon Oct 17 02:47:17 volumio volumio[1129]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 17 02:47:17 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 17 02:47:17 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 17 02:47:17 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 17 02:47:17 volumio systemd[1]: Starting Volumio Streaming Daemon... Oct 17 02:47:17 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 17 02:47:18 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Oct 17 02:47:18 volumio volumio-streaming-daemon[1604]: ############################ Oct 17 02:47:18 volumio volumio-streaming-daemon[1604]: # Volumio Streaming Daemon # Oct 17 02:47:18 volumio volumio-streaming-daemon[1604]: # Running on port 7777 # Oct 17 02:47:18 volumio volumio-streaming-daemon[1604]: ############################ Oct 17 02:47:18 volumio volumio-streaming-daemon[1604]: Environment: production Oct 17 02:47:18 volumio volumio[1129]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.834&uuid=61355532533ecf284243ab28ee6bb310" http://updates.volumio.org:7070/downloader-v1/track-device Oct 17 02:47:18 volumio volumio[1129]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 17 02:47:18 volumio volumio[1129]: Dload Upload Total Spent Left Speed Oct 17 02:47:18 volumio volumio[1129]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Oct 17 02:47:18 volumio volumio[1129]: retrying in 5 seconds, trial 0 Oct 17 02:47:18 volumio volumio[1129]: Volumio Calling Home Oct 17 02:47:18 volumio volumio[1129]: error: Upnp client error: Error: read ECONNRESET Oct 17 02:47:18 volumio volumio[1129]: info: touch_display: Setting screensaver timeout to 1 seconds. Oct 17 02:47:18 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:19 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:19 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:19 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 17 02:47:19 volumio volumio[1129]: info: CoreCommandRouter::volumioGetState Oct 17 02:47:19 volumio volumio[1129]: info: CorePlayQueue::getTrack 0 Oct 17 02:47:19 volumio volumio[1129]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 17 02:47:19 volumio volumio[1129]: verbose: ControllerMpd::sendMpdCommand rescan Oct 17 02:47:19 volumio volumio[1129]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 02:47:19 volumio volumio[1129]: TypeError: Cannot read property 'then' of undefined Oct 17 02:47:19 volumio volumio[1129]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:329:6) Oct 17 02:47:19 volumio volumio[1129]: at ControllerMpd.rescanDb (/volumio/app/plugins/music_service/mpd/index.js:1821:15) Oct 17 02:47:19 volumio volumio[1129]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1052:32) Oct 17 02:47:19 volumio volumio[1129]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:903:26) Oct 17 02:47:19 volumio volumio[1129]: at emitTwo (events.js:126:13) Oct 17 02:47:19 volumio volumio[1129]: at Socket.emit (events.js:214:7) Oct 17 02:47:19 volumio volumio[1129]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Oct 17 02:47:19 volumio volumio[1129]: at _combinedTickCallback (internal/process/next_tick.js:131:7) Oct 17 02:47:19 volumio volumio[1129]: at process._tickCallback (internal/process/next_tick.js:180:9) Oct 17 02:47:19 volumio volumio[1129]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 17 02:47:19 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-REENABLED id=1 ssid="F4239C707689-2G" Oct 17 02:47:19 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-REENABLED id=2 ssid="F4239C707689-2G" Oct 17 02:47:19 volumio wpa_supplicant[907]: wlan0: Trying to associate with SSID 'F4239C707689-2G' Oct 17 02:47:20 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Oct 17 02:47:20 volumio wpa_supplicant[907]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=1 ssid="F4239C707689-2G" auth_failures=3 duration=55 reason=CONN_FAILED Oct 17 02:47:20 volumio sudo[1645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-17 02:46 Oct 17 02:47:20 volumio sudo[1645]: 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="74e4cc9de715c64d553d35948d017f973a622b6d" VOLUMIO_FE_VERSION="2be6c28eb9de74ec1f9662ca333f7bd51a232c33" VOLUMIO_BE_VERSION="259a7f2894e1376413ffac331be84e0e9a6173dd" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Sep 24 22:42:43 CEST 2020" VOLUMIO_VERSION="2.834" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="e41ef0f29aa50c1af109d3064a380c69"