-- Logs begin at Mon 2026-10-12 07:01:57 UTC, end at Tue 2026-10-13 06:16:22 UTC. -- Oct 13 06:15:00 volumio1 wireless.js[655]: WIRELESS: No wireless interface, exiting Oct 13 06:15:00 volumio1 systemd[1]: Started LSB: start Samba daemons for the AD DC. Oct 13 06:15:00 volumio1 systemd[1]: wireless.service: control process exited, code=exited status=1 Oct 13 06:15:00 volumio1 systemd[1]: Failed to start Wireless Services. Oct 13 06:15:00 volumio1 systemd[1]: Unit wireless.service entered failed state. Oct 13 06:15:00 volumio1 systemd[1]: Starting Volumio Backend Module... Oct 13 06:15:00 volumio1 systemd[1]: Started Volumio Backend Module. Oct 13 06:15:00 volumio1 systemd[1]: Starting Volumio Streaming Daemon... Oct 13 06:15:00 volumio1 systemd[1]: Started Volumio Streaming Daemon. Oct 13 06:15:02 volumio1 mpd[580]: output: No 'AudioOutput' defined in config file Oct 13 06:15:02 volumio1 mpd[580]: output: Attempt to detect audio output device Oct 13 06:15:02 volumio1 mpd[580]: output: Attempting to detect a alsa audio device Oct 13 06:15:03 volumio1 mpd[580]: output: Successfully detected a alsa audio device Oct 13 06:15:03 volumio1 systemd[1]: Started Music Player Daemon. Oct 13 06:15:03 volumio1 volumio-streaming-daemon[715]: ############################ Oct 13 06:15:03 volumio1 volumio-streaming-daemon[715]: # Volumio Streaming Daemon # Oct 13 06:15:03 volumio1 volumio-streaming-daemon[715]: # Running on port 7777 # Oct 13 06:15:03 volumio1 volumio-streaming-daemon[715]: ############################ Oct 13 06:15:03 volumio1 volumio-streaming-daemon[715]: Environment: production Oct 13 06:15:03 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:03] [info] asio async_connect error: system:111 (Connection refused) Oct 13 06:15:03 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:03] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 06:15:03 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:03] [error] handle_connect error: Underlying Transport Error Oct 13 06:15:04 volumio1 winbind[531]: Starting the Winbind daemon: winbindmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 13 06:15:04 volumio1 winbind[531]: Unable to setup corepath for winbindd: No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 13 06:15:04 volumio1 winbind[531]: Unable to setup corepath for winbindd: No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:04 volumio1 winbind[531]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:04 volumio1 winbind[531]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../source3/winbindd/winbindd.c:1549(main) Oct 13 06:15:04 volumio1 winbind[531]: winbindd version 4.2.14-Debian started. Oct 13 06:15:04 volumio1 winbind[531]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 13 06:15:04 volumio1 winbind[531]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 13 06:15:04 volumio1 winbind[531]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 13 06:15:04 volumio1 winbind[531]: [2026/10/13 06:15:04, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 13 06:15:04 volumio1 winbind[531]: Unable to setup corepath for winbindd: No such file or directory Oct 13 06:15:04 volumio1 winbindd[716]: [2026/10/13 06:15:04.823608, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:04 volumio1 winbindd[716]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:04 volumio1 winbindd[716]: [2026/10/13 06:15:04.827239, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:04 volumio1 winbindd[716]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:04 volumio1 winbindd[732]: [2026/10/13 06:15:04.954295, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Oct 13 06:15:04 volumio1 winbindd[732]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Oct 13 06:15:04 volumio1 systemd[1]: Started LSB: start Winbind daemon. Oct 13 06:15:05 volumio1 winbind[531]: [2026/10/13 06:15:04.823608, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 winbind[531]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:05 volumio1 winbind[531]: [2026/10/13 06:15:04.827239, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 winbind[531]: Unable to open new log file '/var/log/samba/log.winbindd': No such file or directory Oct 13 06:15:05 volumio1 winbind[531]: . Oct 13 06:15:05 volumio1 winbindd[732]: [2026/10/13 06:15:05.111672, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 13 06:15:05 volumio1 winbindd[732]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Oct 13 06:15:05 volumio1 winbindd[733]: [2026/10/13 06:15:05.170364, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 winbindd[733]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO1': No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: Starting NetBIOS name server: nmbdmkdir failed on directory /var/log/samba/cores: No such file or directory Oct 13 06:15:05 volumio1 nmbd[731]: [2026/10/13 06:15:05.238024, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[731]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 13 06:15:05 volumio1 nmbd[544]: Unable to setup corepath for nmbd: No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: [2026/10/13 06:15:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[544]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: [2026/10/13 06:15:05, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[544]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: [2026/10/13 06:15:05, 0] ../source3/nmbd/nmbd.c:908(main) Oct 13 06:15:05 volumio1 nmbd[544]: nmbd version 4.2.14-Debian started. Oct 13 06:15:05 volumio1 nmbd[544]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 13 06:15:05 volumio1 nmbd[544]: [2026/10/13 06:15:05.238024, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[544]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[731]: [2026/10/13 06:15:05.263665, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[731]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[734]: [2026/10/13 06:15:05.314964, 0] ../source3/nmbd/asyncdns.c:157(start_async_dns) Oct 13 06:15:05 volumio1 nmbd[734]: started asyncdns process 735 Oct 13 06:15:05 volumio1 systemd[1]: Started LSB: start Samba NetBIOS nameserver (nmbd). Oct 13 06:15:05 volumio1 systemd[1]: Starting LSB: start Samba SMB/CIFS daemon (smbd)... Oct 13 06:15:05 volumio1 nmbd[544]: [2026/10/13 06:15:05.263665, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:05 volumio1 nmbd[544]: Unable to open new log file '/var/log/samba/log.nmbd': No such file or directory Oct 13 06:15:05 volumio1 nmbd[544]: . Oct 13 06:15:05 volumio1 nmbd[734]: [2026/10/13 06:15:05.484862, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 13 06:15:05 volumio1 nmbd[734]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Oct 13 06:15:13 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:13] [info] asio async_connect error: system:111 (Connection refused) Oct 13 06:15:13 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:13] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 13 06:15:14 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:13] [error] handle_connect error: Underlying Transport Error Oct 13 06:15:15 volumio1 smbd[736]: Starting SMB/CIFS daemon: smbd[2026/10/13 06:15:15, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[736]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15, 0] ../lib/util/util.c:220(directory_create_or_exist) Oct 13 06:15:15 volumio1 smbd[736]: mkdir failed on directory /var/log/samba/cores: No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Oct 13 06:15:15 volumio1 smbd[736]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Oct 13 06:15:15 volumio1 smbd[736]: Unable to setup corepath for smbd: No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[736]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15, 0] ../source3/smbd/server.c:1241(main) Oct 13 06:15:15 volumio1 smbd[736]: smbd version 4.2.14-Debian started. Oct 13 06:15:15 volumio1 smbd[736]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Oct 13 06:15:15 volumio1 smbd[746]: [2026/10/13 06:15:15.280649, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[746]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15.280649, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[736]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[746]: [2026/10/13 06:15:15.289291, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[746]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[746]: [2026/10/13 06:15:15.310476, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[746]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15.289291, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[736]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: [2026/10/13 06:15:15.310476, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 smbd[736]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:15 volumio1 smbd[736]: . Oct 13 06:15:15 volumio1 systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Oct 13 06:15:15 volumio1 systemd[1]: Starting Multi-User System. Oct 13 06:15:15 volumio1 systemd[1]: Reached target Multi-User System. Oct 13 06:15:15 volumio1 systemd[1]: Starting Graphical Interface. Oct 13 06:15:15 volumio1 systemd[1]: Reached target Graphical Interface. Oct 13 06:15:15 volumio1 systemd[1]: Starting Update UTMP about System Runlevel Changes... Oct 13 06:15:15 volumio1 systemd[1]: Started Update UTMP about System Runlevel Changes. Oct 13 06:15:15 volumio1 systemd[1]: Startup finished in 11.783s (kernel) + 1min 11.542s (userspace) = 1min 23.325s. Oct 13 06:15:15 volumio1 winbindd[753]: [2026/10/13 06:15:15.820244, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:15 volumio1 winbindd[753]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Oct 13 06:15:16 volumio1 winbindd[754]: [2026/10/13 06:15:16.009920, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:16 volumio1 winbindd[754]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Oct 13 06:15:16 volumio1 smbd[747]: [2026/10/13 06:15:16.285615, 0] ../lib/util/become_daemon.c:124(daemon_ready) Oct 13 06:15:16 volumio1 smbd[747]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Oct 13 06:15:16 volumio1 smbd[755]: [2026/10/13 06:15:16.327213, 0] ../lib/util/debug.c:603(reopen_logs_internal) Oct 13 06:15:16 volumio1 smbd[755]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Oct 13 06:15:22 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:22 volumio1 volumio[713]: info: ----- Volumio2 ---- Oct 13 06:15:22 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:22 volumio1 volumio[713]: info: ----- System startup ---- Oct 13 06:15:22 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:23 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:23] [connect] Successful connection Oct 13 06:15:28 volumio1 volumio[713]: info: MYVOLUMIO Environment detected Oct 13 06:15:29 volumio1 volumio[713]: info: Plugin folders cleanup Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning into folder /volumio/app/plugins/ Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category audio_interface Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category miscellanea Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category music_service Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category plugins.json Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category system_controller Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category user_interface Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning into folder /data/plugins/ Oct 13 06:15:29 volumio1 volumio[713]: info: Scanning category miscellanea Oct 13 06:15:29 volumio1 volumio[713]: info: Plugin folders cleanup completed Oct 13 06:15:29 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:29 volumio1 volumio[713]: info: ----- Core plugins startup ---- Oct 13 06:15:29 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:29 volumio1 volumio[713]: info: Loading plugins from folder /volumio/app/plugins/ Oct 13 06:15:29 volumio1 volumio[713]: info: Adding plugin upnp to MyMusic Plugins Oct 13 06:15:29 volumio1 volumio[713]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 13 06:15:29 volumio1 volumio[713]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 13 06:15:29 volumio1 volumio[713]: info: Loading plugins from folder /data/plugins/ Oct 13 06:15:29 volumio1 volumio[713]: info: Loading plugin "system"... Oct 13 06:15:29 volumio1 volumio[713]: info: Loading plugin "appearance"... Oct 13 06:15:38 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:38] [connect] Successful connection Oct 13 06:15:42 volumio1 volumio[713]: info: Loading plugin "network"... Oct 13 06:15:42 volumio1 volumio[713]: info: Refreshing Cached IP Addresses Oct 13 06:15:43 volumio1 volumio[713]: info: Loading plugin "services"... Oct 13 06:15:43 volumio1 sudo[779]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 06:15:43 volumio1 volumio[713]: info: Loading plugin "alsa_controller"... Oct 13 06:15:43 volumio1 sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:15:43 volumio1 sudo[779]: pam_unix(sudo:session): session closed for user root Oct 13 06:15:43 volumio1 sudo[781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 06:15:43 volumio1 sudo[781]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:15:43 volumio1 sudo[781]: pam_unix(sudo:session): session closed for user root Oct 13 06:15:44 volumio1 volumio[713]: info: Loading plugin "wizard"... Oct 13 06:15:44 volumio1 volumio[713]: info: Loading plugin "volumio_command_line_client"... Oct 13 06:15:44 volumio1 volumio[713]: info: Loading plugin "upnp"... Oct 13 06:15:44 volumio1 volumio[713]: info: [1791872144278] Starting Upmpd Daemon Oct 13 06:15:44 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:15:44 volumio1 volumio[713]: info: Loading plugin "my_music"... Oct 13 06:15:44 volumio1 volumio[713]: info: Loading plugin "mpd"... Oct 13 06:15:46 volumio1 volumio[713]: info: Loading plugin "upnp_browser"... Oct 13 06:15:49 volumio1 volumio[713]: info: Loading plugin "networkfs"... Oct 13 06:15:50 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:15:50 volumio1 volumio[713]: info: Loading plugin "alarm-clock"... Oct 13 06:15:52 volumio1 volumio[713]: info: Loading plugin "airplay_emulation"... Oct 13 06:15:52 volumio1 volumio[713]: info: Starting Shairport Sync Oct 13 06:15:52 volumio1 volumio[713]: info: Loading plugin "last_100"... Oct 13 06:15:52 volumio1 volumio[713]: info: Loading plugin "webradio"... Oct 13 06:15:52 volumio1 volumio[713]: info: Loading plugin "i2s_dacs"... Oct 13 06:15:52 volumio1 volumio[713]: info: I2S DAC not set, start Auto-detection Oct 13 06:15:53 volumio1 volumio[713]: info: Loading plugin "volumiodiscovery"... Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** For more information see Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:15:53 volumio1 node[713]: *** WARNING *** For more information see Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** For more information see Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 13 06:15:53 volumio1 volumio[713]: *** WARNING *** For more information see Oct 13 06:15:53 volumio1 volumio[713]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 13 06:15:53 volumio1 volumio[713]: Discovery: StartAdv! undefined Oct 13 06:15:53 volumio1 volumio[713]: Discovery: Started advertising... Volumio1 - undefined Oct 13 06:15:53 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 13 06:15:53 volumio1 volumio[713]: info: Loading plugin "auto_play"... Oct 13 06:15:53 volumio1 volumio[713]: info: ControllerAutoPlay - connecting mpd on host: localhost; port: 6600 Oct 13 06:15:53 volumio1 volumio[713]: info: Loading plugin "autostart"... Oct 13 06:15:54 volumio1 volumio-remote-updater[521]: [2026-10-13 06:15:53] [connect] Successful connection Oct 13 06:15:54 volumio1 volumio[713]: info: Applying required configuration parameters for plugin autostart Oct 13 06:15:54 volumio1 volumio[713]: info: Loading plugin "outputs"... Oct 13 06:15:54 volumio1 volumio[713]: info: Loading plugin "albumart"... Oct 13 06:15:54 volumio1 volumio[713]: info: Plugin example_plugin is not enabled Oct 13 06:15:54 volumio1 volumio[713]: info: Loading plugin "inputs"... Oct 13 06:15:54 volumio1 volumio[713]: info: Loading plugin "updater_comm"... Oct 13 06:15:56 volumio1 volumio[713]: info: Plugin mpdemulation is not enabled Oct 13 06:15:56 volumio1 volumio[713]: info: Loading plugin "rest_api"... Oct 13 06:15:57 volumio1 volumio[713]: info: Loading plugin "websocket"... Oct 13 06:15:57 volumio1 volumio[713]: info: ___________ START PLUGINS ___________ Oct 13 06:15:57 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:15:57 volumio1 volumio[713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:15:57 volumio1 volumio[713]: info: [1791872157585] CoreMusicLibrary::Adding element Media Servers Oct 13 06:15:57 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:15:58 volumio1 volumio[713]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 06:15:58 volumio1 volumio[713]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 13 06:15:58 volumio1 volumio[713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:15:58 volumio1 volumio[713]: info: [1791872158750] CoreMusicLibrary::Adding element Last_100 Oct 13 06:15:58 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:15:58 volumio1 volumio[713]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 13 06:15:58 volumio1 volumio[713]: info: [1791872158790] CoreMusicLibrary::Adding element Webradio Oct 13 06:15:58 volumio1 volumio[713]: Forking 1 albumart workers Oct 13 06:15:58 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:15:58 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 13 06:15:58 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:58 volumio1 volumio[713]: info: ----- MyVolumio plugins startup ---- Oct 13 06:15:58 volumio1 volumio[713]: info: ------------------------------------------- Oct 13 06:15:58 volumio1 volumio[713]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 13 06:15:59 volumio1 volumio[713]: info: Loading i18n strings for locale ja Oct 13 06:15:59 volumio1 volumio[713]: Updating browse sources language Oct 13 06:15:59 volumio1 volumio[713]: Cannot find translation for sourceMedia Servers Oct 13 06:15:59 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 13 06:15:59 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:15:59 volumio1 volumio[713]: info: CoreCommandRouter::initPlayerControls Oct 13 06:15:59 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:15:59 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: info: BOOT COMPLETED Oct 13 06:16:00 volumio1 volumio[713]: [Metrics] CommandRouter: 37s 674.65ms Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:16:00 volumio1 volumio[713]: ALSA lib pcm_hw.c:1713:(_snd_pcm_hw_open) Invalid value for card Oct 13 06:16:00 volumio1 volumio[713]: aplay: main:722: audio open error: No such file or directory Oct 13 06:16:00 volumio1 volumio[713]: Cannot play startup sound Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::Close All Modals sent Oct 13 06:16:00 volumio1 volumio[713]: info: CoreCommandRouter::Close All Modals sent Oct 13 06:16:00 volumio1 volumio[713]: Express server listening on port 3000 Oct 13 06:16:00 volumio1 volumio[713]: [Metrics] WebUI: 41s 829.30ms Oct 13 06:16:00 volumio1 volumio[713]: Volumio Calling Home Oct 13 06:16:01 volumio1 volumio[713]: info: CoreStateMachine::resetVolumioState Oct 13 06:16:01 volumio1 volumio[713]: info: CoreStateMachine::getcurrentVolume Oct 13 06:16:01 volumio1 volumio[713]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 06:16:02 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:16:02 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 13 06:16:03 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 13 06:16:03 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 13 06:16:03 volumio1 sudo[841]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 13 06:16:03 volumio1 sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:03 volumio1 sudo[841]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:03 volumio1 sudo[843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 13 06:16:03 volumio1 sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:03 volumio1 sudo[843]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:04 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 13 06:16:04 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 13 06:16:07 volumio1 volumio[713]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 06:16:07 volumio1 volumio[713]: info: Volumio called home Oct 13 06:16:07 volumio1 volumio[713]: info: Setting Device type: Raspberry PI Oct 13 06:16:07 volumio1 volumio[713]: info: MPD running with PID580 Oct 13 06:16:07 volumio1 volumio[713]: ,establishing connection Oct 13 06:16:07 volumio1 sudo[864]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cdetect -y 1 Oct 13 06:16:07 volumio1 sudo[864]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:08 volumio1 sudo[864]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:09 volumio1 volumio-remote-updater[521]: [2026-10-13 06:16:09] [connect] Successful connection Oct 13 06:16:09 volumio1 volumio[713]: Starting albumart workers Oct 13 06:16:09 volumio1 volumio[713]: info: VolumeController:: Volume=undefined Mute =false Oct 13 06:16:09 volumio1 volumio[713]: info: CoreStateMachine::pushState Oct 13 06:16:09 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:09 volumio1 volumio[713]: info: CoreCommandRouter::volumioPushState Oct 13 06:16:09 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:16:09 volumio1 volumio[713]: info: CoreStateMachine::updateTrackBlock Oct 13 06:16:09 volumio1 volumio[713]: info: CorePlayQueue::getTrackBlock Oct 13 06:16:09 volumio1 volumio[713]: info: CoreCommandRouter::volumioRetrievevolume Oct 13 06:16:10 volumio1 volumio[713]: info: Oct 13 06:16:10 volumio1 volumio[713]: ---------------------------- Client requests Volume 5 Oct 13 06:16:10 volumio1 volumio[713]: info: VolumeController::SetAlsaVolume5 Oct 13 06:16:10 volumio1 volumio[713]: info: CoreStateMachine::pushState Oct 13 06:16:10 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:10 volumio1 volumio[713]: info: CoreCommandRouter::volumioPushState Oct 13 06:16:10 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:16:11 volumio1 volumio[713]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 06:16:11 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 13 06:16:12 volumio1 volumio[713]: info: Reloading queue from file Oct 13 06:16:12 volumio1 volumio[713]: error: updateQueue error: null Oct 13 06:16:12 volumio1 volumio[713]: error: Failed LSINFO: Error: [50@0] {} No database Oct 13 06:16:12 volumio1 volumio-remote-updater[521]: [2026-10-13 06:16:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1791872169 101 Oct 13 06:16:13 volumio1 volumio[713]: info: VolumeController:: Volume=undefined Mute =false Oct 13 06:16:13 volumio1 volumio[713]: info: CoreStateMachine::pushState Oct 13 06:16:13 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::volumioPushState Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:16:13 volumio1 volumio[713]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: Invalid card number. Oct 13 06:16:13 volumio1 volumio[713]: info: Setting volume on startup at 5 Oct 13 06:16:13 volumio1 volumio[713]: info: CoreStateMachine::setRepeat true single undefined Oct 13 06:16:13 volumio1 volumio[713]: info: CoreStateMachine::pushState Oct 13 06:16:13 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::volumioPushState Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:16:13 volumio1 volumio[713]: info: CoreStateMachine::setRandom null Oct 13 06:16:13 volumio1 volumio[713]: info: CoreStateMachine::pushState Oct 13 06:16:13 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::volumioPushState Oct 13 06:16:13 volumio1 volumio[713]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 13 06:16:14 volumio1 volumio[713]: info: AutoStart - getting queue Oct 13 06:16:14 volumio1 volumio[713]: info: CoreCommandRouter::volumioGetQueue Oct 13 06:16:14 volumio1 volumio[713]: info: CoreStateMachine::getQueue Oct 13 06:16:14 volumio1 volumio[713]: info: CorePlayQueue::getQueue Oct 13 06:16:14 volumio1 volumio[713]: info: AutoStart - start playing -> queue is not empty Oct 13 06:16:14 volumio1 volumio[713]: info: CoreCommandRouter::volumioPlay Oct 13 06:16:14 volumio1 volumio[713]: verbose: UNSET VOLATILE Oct 13 06:16:14 volumio1 volumio[713]: info: CoreStateMachine::play index undefined Oct 13 06:16:14 volumio1 volumio[713]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 13 06:16:14 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:14 volumio1 volumio[713]: info: CoreStateMachine::startPlaybackTimer Oct 13 06:16:14 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:14 volumio1 volumio[713]: info: [1791872174887] ControllerWebradio::clearAddPlayTrack Oct 13 06:16:14 volumio1 volumio[713]: verbose: ControllerMpd::sendMpdCommand stop Oct 13 06:16:15 volumio1 volumio[713]: info: Starting Shairport Sync Oct 13 06:16:15 volumio1 volumio[713]: info: Starting Shairport Sync Oct 13 06:16:15 volumio1 sudo[885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 06:16:15 volumio1 volumio[713]: Discovery: adding bbad7d26-6b9b-45d5-88e0-8a605797e05c Oct 13 06:16:15 volumio1 sudo[885]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:15 volumio1 volumio[713]: info: mDNS: Found device Volumio1 Oct 13 06:16:15 volumio1 volumio[713]: info: CoreCommandRouter::volumioGetState Oct 13 06:16:15 volumio1 sudo[887]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 13 06:16:15 volumio1 volumio[713]: info: CorePlayQueue::getTrack 0 Oct 13 06:16:15 volumio1 sudo[887]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:15 volumio1 sudo[889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 13 06:16:15 volumio1 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 13 06:16:15 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 06:16:15 volumio1 sudo[885]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:15 volumio1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 13 06:16:15 volumio1 sudo[889]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 13 06:16:16 volumio1 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Oct 13 06:16:16 volumio1 volumio[713]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 06:16:16 volumio1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 13 06:16:16 volumio1 sudo[887]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:16 volumio1 volumio[713]: Error: Non okay return status: "ACK [50@0] {} No database" Oct 13 06:16:16 volumio1 volumio[713]: at MPD._checkReturn (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:353:10) Oct 13 06:16:16 volumio1 volumio[713]: at MPD. (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:188:18) Oct 13 06:16:16 volumio1 volumio[713]: at MPD._handleResponse (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:450:3) Oct 13 06:16:16 volumio1 volumio[713]: at MPD._onData (/data/plugins/miscellanea/auto_play/node_modules/node-mpd/src/mpd.js:344:10) Oct 13 06:16:16 volumio1 volumio[713]: at emitOne (events.js:116:13) Oct 13 06:16:16 volumio1 volumio[713]: at Socket.emit (events.js:211:7) Oct 13 06:16:16 volumio1 volumio[713]: at addChunk (_stream_readable.js:263:12) Oct 13 06:16:16 volumio1 volumio[713]: at readableAddChunk (_stream_readable.js:246:13) Oct 13 06:16:16 volumio1 volumio[713]: at Socket.Readable.push (_stream_readable.js:208:10) Oct 13 06:16:16 volumio1 volumio[713]: at TCP.onread (net.js:607:20) Oct 13 06:16:16 volumio1 systemd[1]: Starting UPnP Renderer front-end to MPD... Oct 13 06:16:16 volumio1 systemd[1]: Started UPnP Renderer front-end to MPD. Oct 13 06:16:16 volumio1 sudo[889]: pam_unix(sudo:session): session closed for user root Oct 13 06:16:16 volumio1 volumio[713]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 13 06:16:22 volumio1 sudo[934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-13 06:15 Oct 13 06:16:22 volumio1 sudo[934]: 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="fd3e37ffec12db034ec93f94dbc26e5495d5b91a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Oct 6 12:34:49 CEST 2021" VOLUMIO_VERSION="2.917" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="98c8f08c531a6f0456f17ab17795e35f"