-- Logs begin at Sat 2025-02-15 01:42:35 UTC, end at Sat 2025-02-15 01:44:26 UTC. -- Feb 15 01:43:20 volumio2.9 systemd[1]: Time has been changed Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: eth0: rebinding lease of 192.168.188.29 Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: wlan0: waiting for carrier Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: eth0: leased 192.168.188.29 for 864000 seconds Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: eth0: adding host route to 192.168.188.29 via 127.0.0.1 Feb 15 01:43:20 volumio2.9 dhcpcd[604]: eth0: removing route to 192.168.188.0/24 Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: eth0: adding route to 192.168.188.0/24 Feb 15 01:43:20 volumio2.9 dhcpcd[1041]: eth0: adding default route via 192.168.188.1 Feb 15 01:43:20 volumio2.9 dhcpcd[604]: eth0: removing default route via 192.168.188.1 Feb 15 01:43:20 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:20 volumio2.9 sudo[1126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:20 volumio2.9 sudo[1126]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:20 volumio2.9 sudo[1126]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:21 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:22 volumio2.9 sudo[1142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:22 volumio2.9 smbd[1032]: Starting SMB/CIFS daemon: smbd[2025/02/15 01:43:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22, 0] ../lib/util/util.c:220(directory_create_or_exist) Feb 15 01:43:22 volumio2.9 smbd[1032]: mkdir failed on directory /var/log/samba/cores: No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22, 0] ../source3/lib/dumpcore.c:59(get_default_corepath) Feb 15 01:43:22 volumio2.9 smbd[1032]: Failed to create /var/log/samba/cores for user 0 with mode 0700 Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22, 0] ../source3/lib/dumpcore.c:250(dump_core_setup) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to setup corepath for smbd: No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22, 0] ../source3/smbd/server.c:1241(main) Feb 15 01:43:22 volumio2.9 smbd[1032]: smbd version 4.2.14-Debian started. Feb 15 01:43:22 volumio2.9 smbd[1032]: Copyright Andrew Tridgell and the Samba Team 1992-2014 Feb 15 01:43:22 volumio2.9 sudo[1142]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:22 volumio2.9 winbindd[1033]: [2025/02/15 01:43:22.046100, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 15 01:43:22 volumio2.9 winbindd[1033]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 15 01:43:22 volumio2.9 wpa_supplicant[1027]: Successfully initialized wpa_supplicant Feb 15 01:43:22 volumio2.9 winbindd[1160]: [2025/02/15 01:43:22.057129, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 winbindd[1160]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO2.9': No such file or directory Feb 15 01:43:22 volumio2.9 sudo[1142]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:22 volumio2.9 dhcpcd[1165]: eth0: removing route to 192.168.188.0/24 Feb 15 01:43:22 volumio2.9 dhcpcd[1165]: eth0: removing default route via 192.168.188.1 Feb 15 01:43:22 volumio2.9 dhcpcd[1041]: forked to background, child pid 1165 Feb 15 01:43:22 volumio2.9 sudo[1028]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:22 volumio2.9 smbd[1135]: [2025/02/15 01:43:22.120316, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1135]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1135]: [2025/02/15 01:43:22.122817, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1135]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1135]: [2025/02/15 01:43:22.123195, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1135]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22.120316, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22.122817, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: [2025/02/15 01:43:22.123195, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1032]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1032]: . Feb 15 01:43:22 volumio2.9 systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Feb 15 01:43:22 volumio2.9 winbindd[1173]: [2025/02/15 01:43:22.232659, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 winbindd[1173]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Feb 15 01:43:22 volumio2.9 winbindd[1174]: [2025/02/15 01:43:22.285407, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 winbindd[1174]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Feb 15 01:43:22 volumio2.9 smbd[1170]: [2025/02/15 01:43:22.356045, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 15 01:43:22 volumio2.9 smbd[1170]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 15 01:43:22 volumio2.9 smbd[1176]: [2025/02/15 01:43:22.363476, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 15 01:43:22 volumio2.9 smbd[1176]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 15 01:43:22 volumio2.9 wpa_supplicant[1175]: wlan0: Trying to associate with SSID 'Virtus' Feb 15 01:43:23 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:23 volumio2.9 sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:23 volumio2.9 sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:23 volumio2.9 sudo[1179]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:23 volumio2.9 wpa_supplicant[1175]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Feb 15 01:43:24 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:24 volumio2.9 sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:24 volumio2.9 sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:24 volumio2.9 sudo[1188]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:24 volumio2.9 wpa_supplicant[1175]: wlan0: Trying to associate with SSID 'Virtus' Feb 15 01:43:24 volumio2.9 wpa_supplicant[1175]: wlan0: Associated with c8:0e:14:37:89:03 Feb 15 01:43:24 volumio2.9 wpa_supplicant[1175]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Feb 15 01:43:25 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:25 volumio2.9 sudo[1200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:25 volumio2.9 sudo[1200]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:25 volumio2.9 sudo[1200]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:25 volumio2.9 wpa_supplicant[1175]: wlan0: WPA: Key negotiation completed with c8:0e:14:37:89:03 [PTK=CCMP GTK=CCMP] Feb 15 01:43:25 volumio2.9 wpa_supplicant[1175]: wlan0: CTRL-EVENT-CONNECTED - Connection to c8:0e:14:37:89:03 completed [id=0 id_str=] Feb 15 01:43:25 volumio2.9 dhcpcd[1165]: wlan0: carrier acquired Feb 15 01:43:25 volumio2.9 dhcpcd[1165]: wlan0: rebinding lease of 192.168.188.31 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: wlan0: leased 192.168.188.31 for 864000 seconds Feb 15 01:43:26 volumio2.9 avahi-daemon[816]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.188.31. Feb 15 01:43:26 volumio2.9 avahi-daemon[816]: New relevant interface wlan0.IPv4 for mDNS. Feb 15 01:43:26 volumio2.9 avahi-daemon[816]: Registering new address record for 192.168.188.31 on wlan0.IPv4. Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: eth0: adding route to 192.168.188.0/24 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: eth0: adding default route via 192.168.188.1 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: wlan0: adding host route to 192.168.188.31 via 127.0.0.1 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: wlan0: adding route to 192.168.188.0/24 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: wlan0: adding default route via 192.168.188.1 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: eth0: removing route to 192.168.188.0/24 Feb 15 01:43:26 volumio2.9 dhcpcd[1165]: eth0: removing default route via 192.168.188.1 Feb 15 01:43:26 volumio2.9 wireless.js[930]: trying... Feb 15 01:43:26 volumio2.9 sudo[1275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 15 01:43:26 volumio2.9 sudo[1275]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:26 volumio2.9 sudo[1275]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:26 volumio2.9 wireless.js[930]: Connected to: ----Virtus Feb 15 01:43:26 volumio2.9 wireless.js[930]: ---- Feb 15 01:43:26 volumio2.9 sudo[1284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 01:43:26 volumio2.9 sudo[1284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:26 volumio2.9 sudo[1284]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:26 volumio2.9 wireless.js[930]: ... joined AP, wlan0 IPv4 is 192.168.188.31, ipV6 is undefined Feb 15 01:43:26 volumio2.9 wireless.js[930]: It's done! AP Feb 15 01:43:26 volumio2.9 systemd[1]: Started Wireless Services. Feb 15 01:43:26 volumio2.9 systemd[1]: Starting Volumio Backend Module... Feb 15 01:43:26 volumio2.9 systemd[1]: Started Volumio Backend Module. Feb 15 01:43:26 volumio2.9 systemd[1]: Starting Volumio Streaming Daemon... Feb 15 01:43:26 volumio2.9 systemd[1]: Started Volumio Streaming Daemon. Feb 15 01:43:26 volumio2.9 systemd[1]: Starting Multi-User System. Feb 15 01:43:26 volumio2.9 systemd[1]: Reached target Multi-User System. Feb 15 01:43:26 volumio2.9 systemd[1]: Starting Graphical Interface. Feb 15 01:43:26 volumio2.9 systemd[1]: Reached target Graphical Interface. Feb 15 01:43:26 volumio2.9 systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 15 01:43:26 volumio2.9 systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 15 01:43:26 volumio2.9 systemd[1]: Startup finished in 11.453s (kernel) + 25.852s (userspace) = 37.305s. Feb 15 01:43:26 volumio2.9 volumio-streaming-daemon[1294]: ############################ Feb 15 01:43:26 volumio2.9 volumio-streaming-daemon[1294]: # Volumio Streaming Daemon # Feb 15 01:43:26 volumio2.9 volumio-streaming-daemon[1294]: # Running on port 7777 # Feb 15 01:43:26 volumio2.9 volumio-streaming-daemon[1294]: ############################ Feb 15 01:43:26 volumio2.9 volumio-streaming-daemon[1294]: Environment: production Feb 15 01:43:27 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:43:27] [info] asio async_connect error: system:111 (Connection refused) Feb 15 01:43:27 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:43:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 15 01:43:27 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:43:27] [error] handle_connect error: Underlying Transport Error Feb 15 01:43:27 volumio2.9 ntpd[892]: Listen normally on 4 wlan0 192.168.188.31 UDP 123 Feb 15 01:43:27 volumio2.9 ntpd[892]: peers refreshed Feb 15 01:43:33 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:33 volumio2.9 volumio[1293]: info: ----- Volumio2 ---- Feb 15 01:43:33 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:33 volumio2.9 volumio[1293]: info: ----- System startup ---- Feb 15 01:43:33 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:36 volumio2.9 volumio[1293]: info: MYVOLUMIO Environment detected Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Plugin folders cleanup Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning into folder /volumio/app/plugins/ Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category audio_interface Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category miscellanea Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category music_service Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category plugins.json Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category system_controller Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category user_interface Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning into folder /data/plugins/ Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category music_service Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Scanning category system_controller Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Plugin folders cleanup completed Feb 15 01:43:36 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:36 volumio2.9 volumio[1293]: info: ----- Core plugins startup ---- Feb 15 01:43:36 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Loading plugins from folder /volumio/app/plugins/ Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Adding plugin upnp to MyMusic Plugins Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Loading plugins from folder /data/plugins/ Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Loading plugin "system"... Feb 15 01:43:36 volumio2.9 volumio[1293]: info: Loading plugin "appearance"... Feb 15 01:43:37 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:43:37] [connect] Successful connection Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "network"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Refreshing Cached IP Addresses Feb 15 01:43:41 volumio2.9 sudo[1319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "services"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "alsa_controller"... Feb 15 01:43:41 volumio2.9 sudo[1319]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:41 volumio2.9 sudo[1319]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:41 volumio2.9 sudo[1322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 01:43:41 volumio2.9 sudo[1322]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:41 volumio2.9 sudo[1322]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "wizard"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "volumio_command_line_client"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "upnp"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: [1739583821728] Starting Upmpd Daemon Feb 15 01:43:41 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "my_music"... Feb 15 01:43:41 volumio2.9 volumio[1293]: info: Loading plugin "mpd"... Feb 15 01:43:42 volumio2.9 volumio[1293]: info: Loading plugin "upnp_browser"... Feb 15 01:43:43 volumio2.9 volumio[1293]: info: Loading plugin "networkfs"... Feb 15 01:43:44 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 01:43:44 volumio2.9 volumio[1293]: info: Loading plugin "alarm-clock"... Feb 15 01:43:44 volumio2.9 sudo[1343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hdfritz,password=mult1med1a,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.188.1/FritzMaster\SEA2tb\p2p /mnt/NAS/p2p Feb 15 01:43:44 volumio2.9 sudo[1343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:44 volumio2.9 sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.188.1/\\FritzMaster\FritzMaster\SEA2tb /mnt/NAS/p2p Feb 15 01:43:44 volumio2.9 sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: Netfs 'cifs' registered for caching Feb 15 01:43:44 volumio2.9 kernel: Key type cifs.spnego registered Feb 15 01:43:44 volumio2.9 kernel: Key type cifs.idmap registered Feb 15 01:43:44 volumio2.9 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 15 01:43:44 volumio2.9 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: Duplicate cookie detected Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: O-cookie c=1c2639a2 [p=c32d1595 fl=222 nc=0 na=1] Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: O-cookie d=f4fad696 n=bf3147f1 Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: O-key=[8] '020001bdc0a8bc01' Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: N-cookie c=d66cb93a [p=c32d1595 fl=2 nc=0 na=1] Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: N-cookie d=f4fad696 n=8b6a8816 Feb 15 01:43:44 volumio2.9 kernel: FS-Cache: N-key=[8] '020001bdc0a8bc01' Feb 15 01:43:44 volumio2.9 sudo[1345]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:44 volumio2.9 kernel: Status code returned 0xc000006d STATUS_LOGON_FAILURE Feb 15 01:43:44 volumio2.9 kernel: CIFS VFS: Send error in SessSetup = -13 Feb 15 01:43:44 volumio2.9 kernel: CIFS VFS: cifs_mount failed w/return code = -13 Feb 15 01:43:44 volumio2.9 sudo[1343]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:44 volumio2.9 volumio[1293]: info: Loading plugin "airplay_emulation"... Feb 15 01:43:44 volumio2.9 volumio[1293]: info: Starting Shairport Sync Feb 15 01:43:44 volumio2.9 volumio[1293]: info: Loading plugin "last_100"... Feb 15 01:43:44 volumio2.9 volumio[1293]: info: Loading plugin "webradio"... Feb 15 01:43:45 volumio2.9 volumio[1293]: info: Loading plugin "i2s_dacs"... Feb 15 01:43:45 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 01:43:45 volumio2.9 volumio[1293]: info: Loading plugin "volumiodiscovery"... Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** For more information see Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 01:43:45 volumio2.9 node[1293]: *** WARNING *** For more information see Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** For more information see Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 15 01:43:45 volumio2.9 volumio[1293]: *** WARNING *** For more information see Feb 15 01:43:45 volumio2.9 volumio[1293]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 15 01:43:45 volumio2.9 volumio[1293]: Discovery: StartAdv! undefined Feb 15 01:43:45 volumio2.9 volumio[1293]: Discovery: Started advertising... Volumio2 - undefined Feb 15 01:43:45 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 01:43:45 volumio2.9 volumio[1293]: info: Loading plugin "outputs"... Feb 15 01:43:45 volumio2.9 volumio[1293]: info: Loading plugin "albumart"... Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Plugin example_plugin is not enabled Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Loading plugin "inputs"... Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Loading plugin "updater_comm"... Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Plugin mpdemulation is not enabled Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Loading plugin "rest_api"... Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Loading plugin "websocket"... Feb 15 01:43:46 volumio2.9 volumio[1293]: info: Loading plugin "radio_paradise"... Feb 15 01:43:46 volumio2.9 volumio[1293]: Forking 3 albumart workers Feb 15 01:43:47 volumio2.9 volumio[1293]: info: Applying required configuration parameters for plugin radio_paradise Feb 15 01:43:47 volumio2.9 volumio[1293]: info: [1739583827535] [RadioParadise] API delay: 5 Feb 15 01:43:47 volumio2.9 volumio[1293]: info: Loading plugin "spop"... Feb 15 01:43:48 volumio2.9 volumio[1293]: Starting albumart workers Feb 15 01:43:48 volumio2.9 volumio[1293]: Starting albumart workers Feb 15 01:43:48 volumio2.9 volumio[1293]: Starting albumart workers Feb 15 01:43:51 volumio2.9 volumio[1293]: info: Loading plugin "backup_restore"... Feb 15 01:43:51 volumio2.9 volumio[1293]: info: ___________ START PLUGINS ___________ Feb 15 01:43:51 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 01:43:51 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 01:43:51 volumio2.9 volumio[1293]: info: [1739583831862] CoreMusicLibrary::Adding element Media Servers Feb 15 01:43:51 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:43:52 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:43:52] [connect] Successful connection Feb 15 01:43:52 volumio2.9 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 01:43:52 volumio2.9 volumio[1293]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 01:43:52 volumio2.9 volumio[1293]: info: [1739583832093] CoreMusicLibrary::Adding element Last_100 Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 01:43:52 volumio2.9 volumio[1293]: info: [1739583832099] CoreMusicLibrary::Adding element Webradio Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 15 01:43:52 volumio2.9 volumio[1293]: info: [1739583832131] CoreMusicLibrary::Adding element Radio Paradise Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 15 01:43:52 volumio2.9 volumio[1293]: [SpotifyConnect] Creating VLS config file Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hosts Feb 15 01:43:52 volumio2.9 volumio[1293]: [SpotifyConnect] Starting metadata listener Feb 15 01:43:52 volumio2.9 sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:52 volumio2.9 sudo[1477]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:52 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:52 volumio2.9 volumio[1293]: info: ----- MyVolumio plugins startup ---- Feb 15 01:43:52 volumio2.9 volumio[1293]: info: ------------------------------------------- Feb 15 01:43:52 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 15 01:43:52 volumio2.9 sudo[1484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volspotconnect.service Feb 15 01:43:52 volumio2.9 volumio[1293]: info: Loading i18n strings for locale it Feb 15 01:43:52 volumio2.9 sudo[1484]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:52 volumio2.9 volumio[1293]: Updating browse sources language Feb 15 01:43:52 volumio2.9 volumio[1293]: Cannot find translation for sourceMedia Servers Feb 15 01:43:52 volumio2.9 volumio[1293]: Cannot find translation for sourceRadio Paradise Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:43:52 volumio2.9 systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 01:43:52 volumio2.9 systemd[1]: Started Volspotconnect2 Daemon. Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::initPlayerControls Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1491]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 01:43:52 volumio2.9 volumio[1491]: Reading Config from "/tmp/volspotify.toml" Feb 15 01:43:52 volumio2.9 volumio[1491]: Malformed config key: missing field `Authentication` Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 15 01:43:52 volumio2.9 volumio[1293]: info: BOOT COMPLETED Feb 15 01:43:52 volumio2.9 volumio[1293]: [Metrics] CommandRouter: 19s 560.75ms Feb 15 01:43:52 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 01:43:52 volumio2.9 sudo[1484]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:54 volumio2.9 systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 01:43:54 volumio2.9 systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 01:43:54 volumio2.9 systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 01:43:54 volumio2.9 systemd[1]: Started Volspotconnect2 Daemon. Feb 15 01:43:54 volumio2.9 volumio[1501]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 01:43:54 volumio2.9 volumio[1501]: Reading Config from "/tmp/volspotify.toml" Feb 15 01:43:54 volumio2.9 volumio[1501]: Malformed config key: missing field `Authentication` Feb 15 01:43:56 volumio2.9 systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 01:43:56 volumio2.9 systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 01:43:56 volumio2.9 systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 01:43:56 volumio2.9 systemd[1]: Started Volspotconnect2 Daemon. Feb 15 01:43:56 volumio2.9 volumio[1504]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 01:43:56 volumio2.9 volumio[1504]: Reading Config from "/tmp/volspotify.toml" Feb 15 01:43:56 volumio2.9 volumio[1504]: Malformed config key: missing field `Authentication` Feb 15 01:43:57 volumio2.9 volumio[1293]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 15 01:43:57 volumio2.9 volumio[1293]: info: CoreCommandRouter::Close All Modals sent Feb 15 01:43:57 volumio2.9 volumio[1293]: info: CoreCommandRouter::Close All Modals sent Feb 15 01:43:57 volumio2.9 volumio[1293]: Express server listening on port 3000 Feb 15 01:43:57 volumio2.9 volumio[1293]: [Metrics] WebUI: 26s 254.76ms Feb 15 01:43:57 volumio2.9 volumio[1293]: Volumio Calling Home Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreStateMachine::resetVolumioState Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreStateMachine::getcurrentVolume Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioRetrievevolume Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 15 01:43:58 volumio2.9 sudo[1514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 15 01:43:58 volumio2.9 sudo[1514]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:58 volumio2.9 sudo[1516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 15 01:43:58 volumio2.9 sudo[1514]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:58 volumio2.9 sudo[1516]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:43:58 volumio2.9 sudo[1516]: pam_unix(sudo:session): session closed for user root Feb 15 01:43:58 volumio2.9 volumio[1293]: info: Volumio called home Feb 15 01:43:58 volumio2.9 volumio[1293]: info: Setting Device type: Raspberry PI Feb 15 01:43:58 volumio2.9 volumio[1293]: [SpotifyConnect] Vollibrespot Daemon service started! Feb 15 01:43:58 volumio2.9 volumio[1293]: [Metrics] SpotifyConnect: 6s 754.93ms Feb 15 01:43:58 volumio2.9 volumio[1293]: info: Cannot mount NAS p2p at system boot, trial number 1 ,retrying in 5 seconds Feb 15 01:43:58 volumio2.9 volumio[1293]: info: MPD running with PID824 Feb 15 01:43:58 volumio2.9 volumio[1293]: ,establishing connection Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 15 01:43:58 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 15 01:43:59 volumio2.9 systemd[1]: volspotconnect.service holdoff time over, scheduling restart. Feb 15 01:43:59 volumio2.9 systemd[1]: Stopping Volspotconnect2 Daemon... Feb 15 01:43:59 volumio2.9 systemd[1]: Starting Volspotconnect2 Daemon... Feb 15 01:43:59 volumio2.9 systemd[1]: Started Volspotconnect2 Daemon. Feb 15 01:43:59 volumio2.9 volumio[1531]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-07-11 Feb 15 01:43:59 volumio2.9 volumio[1531]: Reading Config from "/tmp/volspotify.toml" Feb 15 01:43:59 volumio2.9 volumio[1531]: [Vollibrespot] : Using Alsa backend with device: plughw:2 Feb 15 01:43:59 volumio2.9 volumio[1531]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Feb 15 01:43:59 volumio2.9 volumio[1293]: info: Reloading queue from file Feb 15 01:43:59 volumio2.9 volumio[1293]: error: updateQueue error: null Feb 15 01:43:59 volumio2.9 volumio[1293]: error: Failed LSINFO: Error: [50@0] {} No such directory Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreStateMachine::setRepeat null single undefined Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreStateMachine::setRandom null Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:44:00 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:44:01 volumio2.9 volumio[1293]: info: Starting Shairport Sync Feb 15 01:44:01 volumio2.9 sudo[1536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 15 01:44:01 volumio2.9 volumio[1293]: info: Starting Shairport Sync Feb 15 01:44:01 volumio2.9 sudo[1536]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:44:01 volumio2.9 sudo[1539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 01:44:01 volumio2.9 systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 15 01:44:01 volumio2.9 systemd[1]: Started UPnP Renderer front-end to MPD. Feb 15 01:44:01 volumio2.9 sudo[1539]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:44:01 volumio2.9 sudo[1536]: pam_unix(sudo:session): session closed for user root Feb 15 01:44:01 volumio2.9 sudo[1546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 15 01:44:01 volumio2.9 sudo[1546]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:44:01 volumio2.9 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 15 01:44:01 volumio2.9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 01:44:01 volumio2.9 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 15 01:44:01 volumio2.9 sudo[1539]: pam_unix(sudo:session): session closed for user root Feb 15 01:44:01 volumio2.9 systemd[1]: Starting Shairport Sync - AirPlay Audio Receiver... Feb 15 01:44:01 volumio2.9 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 15 01:44:01 volumio2.9 sudo[1546]: pam_unix(sudo:session): session closed for user root Feb 15 01:44:02 volumio2.9 volumio[1293]: Discovery: adding 69ca01d1-c81b-40a9-b0dd-658c56a69ff7 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: mDNS: Found device Volumio2 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetState Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: Shairport-Sync Started Feb 15 01:44:02 volumio2.9 volumio[1293]: Error adding Membership: Error: addMembership EINVAL Feb 15 01:44:02 volumio2.9 volumio[1293]: info: Shairport-Sync Started Feb 15 01:44:02 volumio2.9 volumio[1293]: info: Upmpdcli Daemon Started Feb 15 01:44:02 volumio2.9 volumio[1293]: Discovery: this is already registered, 69ca01d1-c81b-40a9-b0dd-658c56a69ff7 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: mDNS: Found device Volumio2 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetState Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetState Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetVisibleSources Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 15 01:44:02 volumio2.9 volumio[1293]: info: Listing playlists Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetQueue Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CoreStateMachine::getQueue Feb 15 01:44:02 volumio2.9 volumio[1293]: info: CorePlayQueue::getQueue Feb 15 01:44:03 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioGetState Feb 15 01:44:03 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:03 volumio2.9 volumio[1293]: info: Checking Spotify Web API Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 15 01:44:03 volumio2.9 volumio[1293]: info: Adding plugin bluetooth to MyMusic Plugins Feb 15 01:44:03 volumio2.9 volumio[1293]: info: Adding plugin cd_controller to MyMusic Plugins Feb 15 01:44:03 volumio2.9 volumio[1293]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 15 01:44:03 volumio2.9 volumio[1293]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 15 01:44:03 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 15 01:44:04 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 15 01:44:05 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 15 01:44:05 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 15 01:44:05 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 01:44:05 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 15 01:44:05 volumio2.9 volumio[1293]: info: Starting MyVolumio Remote Streaming Endpoints Feb 15 01:44:05 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 15 01:44:05 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 15 01:44:05 volumio2.9 volumio[1293]: info: Streaming services startup Feb 15 01:44:05 volumio2.9 volumio[1293]: info: Starting Streaming Daemon Feb 15 01:44:05 volumio2.9 sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 15 01:44:05 volumio2.9 sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:44:05 volumio2.9 volumio[1293]: info: MyVolumio not started Feb 15 01:44:05 volumio2.9 volumio[1293]: info: Initializing device activation check Feb 15 01:44:05 volumio2.9 systemd[1]: Stopping Volumio Streaming Daemon... Feb 15 01:44:05 volumio2.9 volumio[1293]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 15 01:44:05 volumio2.9 systemd[1]: Starting Volumio Streaming Daemon... Feb 15 01:44:05 volumio2.9 sudo[1578]: pam_unix(sudo:session): session closed for user root Feb 15 01:44:05 volumio2.9 systemd[1]: Started Volumio Streaming Daemon. Feb 15 01:44:05 volumio2.9 volumio-streaming-daemon[1588]: ############################ Feb 15 01:44:05 volumio2.9 volumio-streaming-daemon[1588]: # Volumio Streaming Daemon # Feb 15 01:44:05 volumio2.9 volumio-streaming-daemon[1588]: # Running on port 7777 # Feb 15 01:44:05 volumio2.9 volumio-streaming-daemon[1588]: ############################ Feb 15 01:44:05 volumio2.9 volumio-streaming-daemon[1588]: Environment: production Feb 15 01:44:06 volumio2.9 volumio[1293]: info: Cannot mount NAS p2p at system boot, trial number 2 ,retrying in 5 seconds Feb 15 01:44:07 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:44:07] [connect] Successful connection Feb 15 01:44:07 volumio2.9 volumio-remote-updater[758]: [2025-02-15 01:44:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1739583847 101 Feb 15 01:44:10 volumio2.9 volumio-streaming-daemon[1588]: Environment: production Feb 15 01:44:10 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 01:44:10 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 01:44:10 volumio2.9 volumio[1293]: info: Fetching Streaming Services browse cache Feb 15 01:44:11 volumio2.9 volumio[1293]: info: Cannot mount NAS p2p at system boot, trial number 3 ,retrying in 5 seconds Feb 15 01:44:12 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 01:44:16 volumio2.9 volumio[1293]: info: Cannot mount NAS p2p at system boot, trial number 4 ,retrying in 5 seconds Feb 15 01:44:16 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 15 01:44:17 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Feb 15 01:44:17 volumio2.9 sudo[1639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount /mnt/NAS/p2p Feb 15 01:44:17 volumio2.9 sudo[1639]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 15 01:44:17 volumio2.9 sudo[1639]: pam_unix(sudo:session): session closed for user root Feb 15 01:44:17 volumio2.9 volumio[1293]: info: Share p2p successfully unmounted Feb 15 01:44:18 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Feb 15 01:44:20 volumio2.9 volumio[1293]: info: Feb 15 01:44:20 volumio2.9 volumio[1293]: ---------------------------- MPD announces state update: update Feb 15 01:44:20 volumio2.9 volumio[1293]: info: ControllerMpd::getState Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Feb 15 01:44:20 volumio2.9 volumio[1293]: info: [1739583860443] Database update started Feb 15 01:44:20 volumio2.9 volumio[1293]: info: MPD Database updated - AlbumList cache refreshed Feb 15 01:44:20 volumio2.9 volumio[1293]: info: Feb 15 01:44:20 volumio2.9 volumio[1293]: ---------------------------- MPD announces state update: database Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Feb 15 01:44:20 volumio2.9 volumio[1293]: info: Feb 15 01:44:20 volumio2.9 volumio[1293]: ---------------------------- MPD announces state update: update Feb 15 01:44:20 volumio2.9 volumio[1293]: info: ControllerMpd::getState Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Feb 15 01:44:20 volumio2.9 volumio[1293]: info: ControllerMpd::getState Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::sendMpdCommand status Feb 15 01:44:20 volumio2.9 volumio[1293]: info: sendMpdCommand status took 195 milliseconds Feb 15 01:44:20 volumio2.9 volumio[1293]: info: sendMpdCommand status took 139 milliseconds Feb 15 01:44:20 volumio2.9 volumio[1293]: verbose: ControllerMpd::parseState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: Command Router : Notfying DB Updatefalse Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::Close All Modals sent Feb 15 01:44:25 volumio2.9 volumio[1293]: verbose: ControllerMpd::parseState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: ControllerMpd::pushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::servicePushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:25 volumio2.9 volumio[1293]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 15 01:44:25 volumio2.9 volumio[1293]: verbose: CURRENT POSITION 0 Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreStateMachine::syncState stateService stop Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreStateMachine::syncState currentStatus stop Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:44:25 volumio2.9 volumio[1293]: info: No code Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreStateMachine::pushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CorePlayQueue::getTrack 0 Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::volumioPushState Feb 15 01:44:25 volumio2.9 volumio[1293]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 15 01:44:25 volumio2.9 volumio[1293]: info: ------------------------------ 4946ms Feb 15 01:44:25 volumio2.9 volumio[1293]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 01:44:25 volumio2.9 volumio[1293]: TypeError: Cannot read property 'replace' of undefined Feb 15 01:44:25 volumio2.9 volumio[1293]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:198:28) Feb 15 01:44:25 volumio2.9 volumio[1293]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:251:20) Feb 15 01:44:25 volumio2.9 volumio[1293]: at ontimeout (timers.js:482:11) Feb 15 01:44:25 volumio2.9 volumio[1293]: at tryOnTimeout (timers.js:317:5) Feb 15 01:44:25 volumio2.9 volumio[1293]: at Timer.listOnTimeout (timers.js:277:5) Feb 15 01:44:25 volumio2.9 volumio[1293]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 15 01:44:26 volumio2.9 sudo[1672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-15 01:43 Feb 15 01:44:26 volumio2.9 sudo[1672]: 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"