-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Fri 2024-04-26 22:50:54 EDT. -- Apr 26 22:49:00 volumio volumio[721]: info: Plugin mpdemulation is not enabled Apr 26 22:49:00 volumio volumio[721]: info: Loading plugin "rest_api"... Apr 26 22:49:00 volumio volumio[721]: info: Loading plugin "websocket"... Apr 26 22:49:00 volumio volumio[721]: info: Plugin mpd_oled is not enabled Apr 26 22:49:00 volumio volumio[721]: info: Plugin music_services_shield is not enabled Apr 26 22:49:00 volumio volumio[721]: info: Loading i18n strings for locale en Apr 26 22:49:00 volumio volumio[721]: Updating browse sources language Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 22:49:00 volumio volumio[721]: Forking 3 albumart workers Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::initPlayerControls Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:00 volumio volumio[721]: Express server listening on port 3000 Apr 26 22:49:00 volumio volumio[721]: [Metrics] WebUI: 4s 380.10ms Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::resetVolumioState Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::getcurrentVolume Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::pushState Apr 26 22:49:00 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioPushState Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: MPD Permissions set Apr 26 22:49:00 volumio volumio-remote-updater[546]: [2024-04-26 22:49:00] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1714186136 101 Apr 26 22:49:00 volumio volumio[721]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Apr 26 22:49:00 volumio volumio[721]: info: MPD running with PID777 Apr 26 22:49:00 volumio volumio[721]: ,establishing connection Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:00 volumio volumio[721]: info: Reloading queue from file Apr 26 22:49:00 volumio volumio[721]: error: updateQueue error: null Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::setRepeat null single undefined Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::pushState Apr 26 22:49:00 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioPushState Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::setRandom null Apr 26 22:49:00 volumio volumio[721]: info: CoreStateMachine::pushState Apr 26 22:49:00 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioPushState Apr 26 22:49:00 volumio volumio[721]: info: Setting Device type: Raspberry PI Apr 26 22:49:00 volumio volumio[721]: error: updateQueue error: null Apr 26 22:49:00 volumio volumio[721]: info: Completed loading Core Plugins Apr 26 22:49:00 volumio volumio[721]: info: Preparing to generate the ALSA configuration file Apr 26 22:49:00 volumio volumio[721]: info: Asound.conf file unchanged, so no further update is needed Apr 26 22:49:00 volumio volumio[721]: info: Output device has changed, restarting MPD Apr 26 22:49:00 volumio volumio[721]: Starting albumart workers Apr 26 22:49:00 volumio volumio[721]: info: ___________ START PLUGINS ___________ Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 22:49:00 volumio volumio[721]: info: [1714186140790] CoreMusicLibrary::Adding element Last_100 Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 22:49:00 volumio volumio[721]: info: [1714186140797] CoreMusicLibrary::Adding element Webradio Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 22:49:00 volumio volumio[721]: Starting albumart workers Apr 26 22:49:00 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 22:49:00 volumio volumio[721]: info: Volumio Calling Home Apr 26 22:49:00 volumio volumio[721]: Starting albumart workers Apr 26 22:49:00 volumio sudo[825]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 22:49:00 volumio sudo[826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 22:49:00 volumio sudo[826]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:00 volumio sudo[825]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:00 volumio sudo[825]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:00 volumio volumio[721]: info: Completed starting Core Plugins Apr 26 22:49:00 volumio volumio[721]: info: ------------------------------------------- Apr 26 22:49:00 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 22:49:00 volumio volumio[721]: info: ----- MyVolumio plugins startup ---- Apr 26 22:49:00 volumio volumio[721]: info: ------------------------------------------- Apr 26 22:49:00 volumio volumio[721]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 26 22:49:00 volumio systemd[1]: mpd.service: Succeeded. Apr 26 22:49:00 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 22:49:00 volumio systemd[1]: Starting Music Player Daemon... Apr 26 22:49:00 volumio volumio[721]: info: MPD Permissions set Apr 26 22:49:01 volumio volumio[721]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio sh[448]: eth0: probing for an IPv4LL address Apr 26 22:49:01 volumio dhcpcd[488]: eth0: probing for an IPv4LL address Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:01 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:01 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:01 volumio mpd[838]: Apr 26 22:49 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 22:49:01 volumio mpd[838]: Apr 26 22:49 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Apr 26 22:49:01 volumio mpd[838]: Apr 26 22:49 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Apr 26 22:49:01 volumio systemd[1]: Started Music Player Daemon. Apr 26 22:49:01 volumio sudo[826]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:01 volumio volumio[721]: error: updateQueue error: null Apr 26 22:49:03 volumio sh[448]: eth0: probing address 192.168.1.27/24 Apr 26 22:49:03 volumio dhcpcd[488]: eth0: probing address 192.168.1.27/24 Apr 26 22:49:05 volumio volumio[721]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Apr 26 22:49:06 volumio sh[448]: eth0: using IPv4LL address 169.254.170.212 Apr 26 22:49:06 volumio sh[448]: eth0: adding route to 169.254.0.0/16 Apr 26 22:49:06 volumio sh[448]: eth0: adding default route Apr 26 22:49:06 volumio dhcpcd[488]: eth0: using IPv4LL address 169.254.170.212 Apr 26 22:49:06 volumio avahi-daemon[560]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.170.212. Apr 26 22:49:06 volumio avahi-daemon[560]: New relevant interface eth0.IPv4 for mDNS. Apr 26 22:49:06 volumio avahi-daemon[560]: Registering new address record for 169.254.170.212 on eth0.IPv4. Apr 26 22:49:06 volumio dhcpcd[488]: eth0: adding route to 169.254.0.0/16 Apr 26 22:49:06 volumio dhcpcd[488]: eth0: adding default route Apr 26 22:49:06 volumio sh[448]: forked to background, child pid 876 Apr 26 22:49:06 volumio dhcpcd[488]: forked to background, child pid 876 Apr 26 22:49:06 volumio ifplugd(eth0)[664]: client: ifup: interface eth0 already configured Apr 26 22:49:06 volumio sh[448]: eth0=eth0 Apr 26 22:49:06 volumio ifplugd(eth0)[664]: Program executed successfully. Apr 26 22:49:07 volumio ntpd[676]: Listen normally on 3 eth0 169.254.170.212:123 Apr 26 22:49:07 volumio ntpd[676]: new interface(s) found: waking up resolver Apr 26 22:49:08 volumio sudo[904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 22:49:08 volumio sudo[904]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:08 volumio sudo[904]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:08 volumio sudo[906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 22:49:08 volumio sudo[906]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:08 volumio sudo[906]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:08 volumio sudo[910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 26 22:49:08 volumio sudo[910]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:08 volumio systemd[1]: Started Samba NMB Daemon. Apr 26 22:49:08 volumio nmbd[641]: [2024/04/26 22:49:08.637922, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 26 22:49:08 volumio nmbd[641]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Apr 26 22:49:08 volumio systemd[1]: Starting Samba Winbind Daemon... Apr 26 22:49:08 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Apr 26 22:49:08 volumio sudo[910]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:08 volumio volumio[721]: info: Upmpdcli Daemon Started Apr 26 22:49:08 volumio volumio[914]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 26 22:49:08 volumio winbindd[912]: [2024/04/26 22:49:08.900830, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Apr 26 22:49:08 volumio winbindd[912]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Apr 26 22:49:08 volumio winbindd[912]: [2024/04/26 22:49:08.995863, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 26 22:49:08 volumio winbindd[912]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Apr 26 22:49:08 volumio systemd[1]: Started Samba Winbind Daemon. Apr 26 22:49:09 volumio systemd[1]: Starting Samba SMB Daemon... Apr 26 22:49:09 volumio dhcpcd[876]: eth0: leased 192.168.1.27 for 43200 seconds Apr 26 22:49:09 volumio dhcpcd[876]: eth0: adding route to 192.168.1.0/24 Apr 26 22:49:09 volumio avahi-daemon[560]: Registering new address record for 192.168.1.27 on eth0.IPv4. Apr 26 22:49:09 volumio dhcpcd[876]: eth0: changing default route via 192.168.1.1 Apr 26 22:49:09 volumio volumio[721]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 3 Apr 26 22:49:09 volumio volumio[721]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 4 Apr 26 22:49:09 volumio volumio[721]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 5 Apr 26 22:49:09 volumio dhcpcd[876]: eth0: deleting route to 169.254.0.0/16 Apr 26 22:49:09 volumio avahi-daemon[560]: Withdrawing address record for 169.254.170.212 on eth0. Apr 26 22:49:09 volumio avahi-daemon[560]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.170.212. Apr 26 22:49:09 volumio avahi-daemon[560]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.27. Apr 26 22:49:09 volumio dhcpcd[876]: eth0: pid 876 deleted default route via 192.168.1.1 Apr 26 22:49:09 volumio systemd[1]: Started Samba SMB Daemon. Apr 26 22:49:09 volumio systemd[1]: Reached target Multi-User System. Apr 26 22:49:09 volumio systemd[1]: Reached target Graphical Interface. Apr 26 22:49:09 volumio smbd[933]: [2024/04/26 22:49:09.527461, 0] ../lib/util/become_daemon.c:138(daemon_ready) Apr 26 22:49:09 volumio smbd[933]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Apr 26 22:49:09 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Apr 26 22:49:09 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Apr 26 22:49:09 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Apr 26 22:49:09 volumio systemd[1]: Startup finished in 11.189s (kernel) + 25.329s (userspace) = 36.519s. Apr 26 22:49:10 volumio ntpd[676]: Listen normally on 4 eth0 192.168.1.27:123 Apr 26 22:49:10 volumio ntpd[676]: Deleting interface #3 eth0, 169.254.170.212#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs Apr 26 22:49:10 volumio ntpd[676]: new interface(s) found: waking up resolver Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654386, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:10 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654551, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:10 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13352 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654625, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:10 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654687, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:10 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13353 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654749, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:10 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654808, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:10 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13354 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654867, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:10 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654923, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:10 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13355 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.654986, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:10 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:10 volumio nmbd[641]: [2024/04/26 22:49:10.655046, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:10 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13356 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:11 volumio volumio[721]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.569&uuid=bfca5b1ae0bcdf9cc58e431e642af968" http://updates.volumio.org/downloader-v1/track-device Apr 26 22:49:11 volumio volumio[721]: % Total % Received % Xferd Average Speed Time Time Time Current Apr 26 22:49:11 volumio volumio[721]: Dload Upload Total Spent Left Speed Apr 26 22:49:11 volumio volumio[721]: [132B blob data] Apr 26 22:49:11 volumio volumio[721]: retrying in 5 seconds, trial 0 Apr 26 22:49:11 volumio volumio[721]: info: Volumio Calling Home Apr 26 22:49:11 volumio volumio[721]: info: Volumio called home Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656303, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:11 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656430, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:11 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13352 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656500, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:11 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656560, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:11 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13353 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656622, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:11 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656680, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:11 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13354 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656741, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:11 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656798, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:11 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13355 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656857, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:11 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:11 volumio nmbd[641]: [2024/04/26 22:49:11.656932, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:11 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13356 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:12 volumio volumio[721]: info: Discovery: adding 74371253-3e27-44e5-a15f-1456f525a51e Apr 26 22:49:12 volumio volumio[721]: info: Discovery: Found device Volumio Apr 26 22:49:12 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:12 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658169, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:12 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658297, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:12 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13352 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658365, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:12 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658428, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:12 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13353 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658489, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:12 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658548, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:12 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13354 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658607, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:12 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658664, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:12 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13355 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658723, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:12 volumio nmbd[641]: Packet send failed to 169.254.255.255(137) ERRNO=Network is unreachable Apr 26 22:49:12 volumio nmbd[641]: [2024/04/26 22:49:12.658778, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Apr 26 22:49:12 volumio nmbd[641]: retransmit_or_expire_response_records: Failed to resend packet id 13356 to IP 169.254.255.255 on subnet 169.254.170.212 Apr 26 22:49:13 volumio volumio[914]: ..................................................++++ Apr 26 22:49:13 volumio nmbd[641]: [2024/04/26 22:49:13.659810, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:49:13 volumio nmbd[641]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin bluetooth to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin multiroom to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin metavolumio to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin cd_controller to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 26 22:49:14 volumio volumio[721]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 26 22:49:15 volumio volumio[721]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 26 22:49:15 volumio volumio[721]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 26 22:49:15 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:15 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:15 volumio volumio[721]: info: Starting MyVolumio Remote Streaming Endpoints Apr 26 22:49:15 volumio volumio[721]: info: MyVolumio login type: Token Apr 26 22:49:15 volumio volumio[721]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 26 22:49:15 volumio volumio[721]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 26 22:49:16 volumio volumio[721]: info: Starting Streaming Service Transparent Proxy Apr 26 22:49:16 volumio volumio[721]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 26 22:49:16 volumio volumio[721]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 26 22:49:16 volumio volumio[721]: info: Streaming services startup Apr 26 22:49:16 volumio volumio[721]: info: Starting Streaming Daemon Apr 26 22:49:16 volumio volumio[721]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 26 22:49:16 volumio sudo[991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 22:49:16 volumio sudo[991]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:16 volumio sudo[991]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:16 volumio volumio[721]: error: Cannot start Volumio Streaming Daemon Apr 26 22:49:16 volumio volumio[721]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 22:49:16 volumio volumio[721]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 22:49:16 volumio volumio[721]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 26 22:49:16 volumio volumio[721]: STREAMING PROXY: Starting server on port 3245 Apr 26 22:49:16 volumio volumio[721]: Node JS runtime: 14 Apr 26 22:49:17 volumio volumio[721]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 6 Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::volumioGetVisibleSources Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:17 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:17 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 26 22:49:17 volumio volumio[721]: info: Received Get System Info Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 26 22:49:17 volumio volumio[721]: info: Discovery: Getting this device information Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:17 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:17 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:17 volumio volumio[721]: info: Listing playlists Apr 26 22:49:17 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 26 22:49:19 volumio volumio[721]: info: MyVolumio token set successfully Apr 26 22:49:19 volumio volumio[721]: info: MYVOLUMIO: Adding device Apr 26 22:49:19 volumio volumio[721]: info: MYVOLUMIO: Evaluating Server Apr 26 22:49:20 volumio systemd[1]: systemd-fsckd.service: Succeeded. Apr 26 22:49:20 volumio volumio[721]: info: MyVolumio status changed Apr 26 22:49:20 volumio volumio[721]: info: Streaming services startup Apr 26 22:49:20 volumio volumio[721]: info: Starting Streaming Daemon Apr 26 22:49:20 volumio volumio[721]: info: Removing browser output: myVolumio user plan is not superstar Apr 26 22:49:20 volumio volumio[721]: info: Removing audio output: Apr 26 22:49:20 volumio volumio[721]: info: Stoppping Tunnel 1 Apr 26 22:49:20 volumio sudo[1016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 26 22:49:20 volumio sudo[1016]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:20 volumio sudo[1016]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:20 volumio volumio[721]: error: Cannot start Volumio Streaming Daemon Apr 26 22:49:20 volumio volumio[721]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 26 22:49:20 volumio volumio[721]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 26 22:49:20 volumio sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 26 22:49:20 volumio sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:49:20 volumio sudo[1019]: pam_unix(sudo:session): session closed for user root Apr 26 22:49:20 volumio volumio[721]: info: Remote SSH Stopped Apr 26 22:49:20 volumio volumio[721]: info: Setting Geolocation for MyVolumio to us1 Apr 26 22:49:20 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:20 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:20 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:20 volumio volumio[721]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 26 22:49:21 volumio volumio[721]: info: Updating MyVolumio device info Apr 26 22:49:21 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:21 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:21 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:21 volumio volumio[721]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 26 22:49:23 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Apr 26 22:49:24 volumio volumio[721]: info: MYVOLUMIO: Adding device Apr 26 22:49:24 volumio volumio[721]: info: MYVOLUMIO: Evaluating Server Apr 26 22:49:26 volumio volumio[721]: info: Setting Geolocation for MyVolumio to us1 Apr 26 22:49:26 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:26 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:26 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:26 volumio volumio[721]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 26 22:49:27 volumio volumio[721]: info: Updating MyVolumio device info Apr 26 22:49:27 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:27 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:27 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:27 volumio volumio[721]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 26 22:49:27 volumio volumio[914]: ................................................................................................................................................................++++ Apr 26 22:49:27 volumio volumio[914]: e is 65537 (0x010001) Apr 26 22:49:27 volumio volumio[914]: writing RSA key Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 26 22:49:30 volumio volumio[721]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::volumioGetState Apr 26 22:49:30 volumio volumio[721]: info: CorePlayQueue::getTrack 0 Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 26 22:49:30 volumio volumio[721]: verbose: New Socket.io Connection to 192.168.1.27 from 192.168.1.134 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/124.0.0.0 Safari/537.36 Total Clients: 8 Apr 26 22:49:30 volumio volumio-remote-updater[546]: No test mode Apr 26 22:49:30 volumio volumio-remote-updater[546]: No alpha test mode Apr 26 22:49:30 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 26 22:49:31 volumio volumio[721]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES



 

 

 



NEW ADDITIONS



 

 

","title":"Update v3.631","updateavailable":true} Apr 26 22:49:31 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 22:49:35 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 22:49:36 volumio volumio[721]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 26 22:49:36 volumio volumio[721]: info: Completed starting MyVolumio Plugin Apr 26 22:49:36 volumio volumio[721]: info: BOOT COMPLETED Apr 26 22:49:36 volumio volumio[721]: [Metrics] CommandRouter: 39s 543.26ms Apr 26 22:49:36 volumio volumio[721]: info: CoreCommandRouter::volumiosetStartupVolume Apr 26 22:49:36 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 22:49:36 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 22:49:36 volumio volumio[721]: info: CoreCommandRouter::Close All Modals sent Apr 26 22:49:36 volumio volumio[721]: info: CoreCommandRouter::Close All Modals sent Apr 26 22:49:37 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 26 22:49:37 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 26 22:49:37 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 26 22:49:41 volumio kernel: xhci_hcd 0000:01:00.0: ERROR Transfer event for disabled endpoint slot 2 ep 1 Apr 26 22:49:41 volumio kernel: xhci_hcd 0000:01:00.0: @000000042a113290 00000000 00000000 0e000000 02028000 Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: info: Retrieving Cloud Streaming UI Apr 26 22:49:46 volumio volumio[721]: info: Getting Tidal Cloud Configuration Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: info: Getting Qobuz Cloud Configuration Apr 26 22:49:46 volumio volumio[721]: info: Asking plugin for UI Config Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: info: Getting Spotify Cloud Configuration Apr 26 22:49:46 volumio volumio[721]: info: Asking plugin for UI Config Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: info: Saving Spotify Acccount Apr 26 22:49:46 volumio volumio[721]: info: Got Tidal Cloud Configuration Apr 26 22:49:46 volumio volumio[721]: info: Got it Apr 26 22:49:46 volumio volumio[721]: info: Got it Apr 26 22:49:46 volumio volumio[721]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 26 22:49:46 volumio volumio[721]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::volumioGetBrowseSources Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:49:46 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 26 22:49:50 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:49:54 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:49:58 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:49:58 volumio ntpd[676]: Soliciting pool server 198.71.50.75 Apr 26 22:49:58 volumio ntpd[676]: Soliciting pool server 199.68.201.234 Apr 26 22:49:59 volumio ntpd[676]: Soliciting pool server 198.199.14.19 Apr 26 22:49:59 volumio ntpd[676]: Soliciting pool server 208.67.72.43 Apr 26 22:49:59 volumio ntpd[676]: Soliciting pool server 107.172.43.182 Apr 26 22:50:00 volumio ntpd[676]: Soliciting pool server 45.79.111.167 Apr 26 22:50:00 volumio ntpd[676]: Soliciting pool server 147.182.158.78 Apr 26 22:50:00 volumio ntpd[676]: Soliciting pool server 74.50.98.131 Apr 26 22:50:01 volumio ntpd[676]: Soliciting pool server 74.6.168.72 Apr 26 22:50:01 volumio ntpd[676]: Soliciting pool server 5.78.89.3 Apr 26 22:50:01 volumio ntpd[676]: Soliciting pool server 44.31.46.123 Apr 26 22:50:02 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:02 volumio ntpd[676]: Soliciting pool server 104.194.8.227 Apr 26 22:50:02 volumio ntpd[676]: Soliciting pool server 152.70.159.102 Apr 26 22:50:03 volumio ntpd[676]: Soliciting pool server 66.228.59.187 Apr 26 22:50:03 volumio ntpd[676]: Soliciting pool server 2607:ff50:0:1a::10 Apr 26 22:50:04 volumio ntpd[676]: Soliciting pool server 64.142.54.12 Apr 26 22:50:05 volumio ntpd[676]: Soliciting pool server 47.251.24.122 Apr 26 22:50:32 volumio ntpd[676]: receive: Unexpected origin timestamp 0xe9d6e65d.7f9b2052 does not match aorg 0000000000.00000000 from server@74.6.168.72 xmt 0xe9d6e678.374aa61c Apr 26 22:50:32 volumio ntpd[676]: receive: Unexpected origin timestamp 0xe9d6e65d.7fa14d9d does not match aorg 0000000000.00000000 from server@199.68.201.234 xmt 0xe9d6e678.3beb27a9 Apr 26 22:50:32 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:36 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:40 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:44 volumio volumio[721]: info: Disabling MyMusic plugin upnp Apr 26 22:50:44 volumio sudo[1062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 26 22:50:44 volumio sudo[1062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 22:50:44 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 26 22:50:44 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:47 volumio nmbd[641]: [2024/04/26 22:50:47.582173, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 26 22:50:47 volumio nmbd[641]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 26 22:50:48 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:52 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 26 22:50:54 volumio volumio[721]: info: Enabling MyMusic plugin upnp Apr 26 22:50:54 volumio volumio[721]: info: Enabling plugin upnp Apr 26 22:50:54 volumio volumio[721]: info: Loading plugin "upnp"... Apr 26 22:50:54 volumio volumio[721]: info: [1714186254294] Starting Upmpd Daemon Apr 26 22:50:54 volumio volumio[721]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 22:50:54 volumio volumio[721]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:50:54 volumio volumio[721]: Error: listen EADDRINUSE: address already in use :::6599 Apr 26 22:50:54 volumio volumio[721]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Apr 26 22:50:54 volumio volumio[721]: at listenInCluster (net.js:1366:12) Apr 26 22:50:54 volumio volumio[721]: at Server.listen (net.js:1452:7) Apr 26 22:50:54 volumio volumio[721]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 26 22:50:54 volumio volumio[721]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 26 22:50:54 volumio volumio[721]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 26 22:50:54 volumio volumio[721]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 26 22:50:54 volumio volumio[721]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Apr 26 22:50:54 volumio volumio[721]: code: 'EADDRINUSE', Apr 26 22:50:54 volumio volumio[721]: errno: -98, Apr 26 22:50:54 volumio volumio[721]: syscall: 'listen', Apr 26 22:50:54 volumio volumio[721]: address: '::', Apr 26 22:50:54 volumio volumio[721]: port: 6599 Apr 26 22:50:54 volumio volumio[721]: } Apr 26 22:50:54 volumio volumio[721]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 22:50:54 volumio sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-26 22:49 Apr 26 22:50:54 volumio sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="893d2e91c55a7857b58762e70c2f65b9d163562b" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="30b77d58bf3c2745acc494ddafed946392a79905" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Oct 2023 03:38:28 PM CEST" VOLUMIO_VERSION="3.569" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4163d3756b55b3bf7c480d7285f68954"