-- Logs begin at Sun 2025-04-27 07:51:13 IST, end at Sun 2025-04-27 07:53:44 IST. -- Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: SME: Trying to authenticate with 04:ba:d6:4a:5d:19 (SSID='Net' freq=2412 MHz) Apr 27 07:52:01 volumio kernel: wlan0: authenticate with 04:ba:d6:4a:5d:19 Apr 27 07:52:01 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 27 07:52:01 volumio kernel: wlan0: send auth to 04:ba:d6:4a:5d:19 (try 1/3) Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: Trying to associate with 04:ba:d6:4a:5d:19 (SSID='Net' freq=2412 MHz) Apr 27 07:52:01 volumio kernel: wlan0: authenticated Apr 27 07:52:01 volumio kernel: wlan0: associate with 04:ba:d6:4a:5d:19 (try 1/3) Apr 27 07:52:01 volumio kernel: wlan0: RX AssocResp from 04:ba:d6:4a:5d:19 (capab=0x1411 status=0 aid=2) Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: Associated with 04:ba:d6:4a:5d:19 Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 27 07:52:01 volumio kernel: wlan0: associated Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: WPA: Key negotiation completed with 04:ba:d6:4a:5d:19 [PTK=CCMP GTK=CCMP] Apr 27 07:52:01 volumio wpa_supplicant[1203]: wlan0: CTRL-EVENT-CONNECTED - Connection to 04:ba:d6:4a:5d:19 completed [id=0 id_str=] Apr 27 07:52:01 volumio dhcpcd[1031]: wlan0: carrier acquired Apr 27 07:52:01 volumio dhcpcd[1031]: wlan0: IAID 84:8d:0c:6b Apr 27 07:52:01 volumio volumio[2067]: ............................................................................++++ Apr 27 07:52:01 volumio volumio[2067]: e is 65537 (0x010001) Apr 27 07:52:01 volumio volumio[2067]: writing RSA key Apr 27 07:52:01 volumio dhcpcd[1031]: wlan0: rebinding lease of 192.168.1.42 Apr 27 07:52:01 volumio dhcpcd[1031]: wlan0: probing address 192.168.1.42/24 Apr 27 07:52:02 volumio dhcpcd[1031]: wlan0: soliciting an IPv6 router Apr 27 07:52:02 volumio ntpd[1319]: Deleting interface #3 wlan0, 192.168.1.42#123, interface stats: received=36, sent=44, dropped=2, active_time=22 secs Apr 27 07:52:02 volumio ntpd[1319]: 17.253.18.131 local addr 192.168.1.42 -> Apr 27 07:52:02 volumio ntpd[1319]: 14.139.60.107 local addr 192.168.1.42 -> Apr 27 07:52:02 volumio ntpd[1319]: 3.111.45.100 local addr 192.168.1.42 -> Apr 27 07:52:02 volumio ntpd[1319]: 95.216.144.226 local addr 192.168.1.42 -> Apr 27 07:52:02 volumio ntpd[1319]: 192.46.211.253 local addr 192.168.1.42 -> Apr 27 07:52:02 volumio ntpd[1319]: 162.159.200.1 local addr 192.168.1.42 -> Apr 27 07:52:05 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 07:52:06 volumio dhcpcd[1031]: wlan0: leased 192.168.1.42 for 86400 seconds Apr 27 07:52:06 volumio avahi-daemon[914]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.42. Apr 27 07:52:06 volumio dhcpcd[1031]: wlan0: adding route to 192.168.1.0/24 Apr 27 07:52:06 volumio dhcpcd[1031]: wlan0: adding default route via 192.168.1.1 Apr 27 07:52:06 volumio avahi-daemon[914]: New relevant interface wlan0.IPv4 for mDNS. Apr 27 07:52:06 volumio avahi-daemon[914]: Registering new address record for 192.168.1.42 on wlan0.IPv4. Apr 27 07:52:06 volumio ntpd[1319]: ntpd exiting on signal 15 (Terminated) Apr 27 07:52:06 volumio systemd[1]: Stopping Network Time Service... Apr 27 07:52:06 volumio systemd[1]: ntp.service: Succeeded. Apr 27 07:52:06 volumio systemd[1]: Stopped Network Time Service. Apr 27 07:52:06 volumio systemd[1]: Starting Network Time Service... Apr 27 07:52:06 volumio ntpd[2149]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 27 07:52:06 volumio ntpd[2149]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 27 07:52:06 volumio systemd[1]: Started Network Time Service. Apr 27 07:52:06 volumio ntpd[2155]: proto: precision = 0.175 usec (-22) Apr 27 07:52:06 volumio ntpd[2155]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 27 07:52:06 volumio ntpd[2155]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 27 07:52:06 volumio ntpd[2155]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 852 days ago Apr 27 07:52:06 volumio ntpd[2155]: Listen and drop on 0 v6wildcard [::]:123 Apr 27 07:52:06 volumio ntpd[2155]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 27 07:52:06 volumio ntpd[2155]: Listen normally on 2 lo 127.0.0.1:123 Apr 27 07:52:06 volumio ntpd[2155]: Listen normally on 3 wlan0 192.168.1.42:123 Apr 27 07:52:06 volumio ntpd[2155]: Listening on routing socket on fd #20 for interface updates Apr 27 07:52:06 volumio ntpd[2155]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 07:52:06 volumio ntpd[2155]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 07:52:07 volumio volumio[1330]: info: Discovery: adding 10f47f61-3bc1-48f1-a20a-ee37a5eab2e2 Apr 27 07:52:07 volumio volumio[1330]: info: Discovery: Found device Volumio Apr 27 07:52:07 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:52:07 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:52:07 volumio ntpd[2155]: Soliciting pool server 160.250.111.68 Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:52:08 volumio ntpd[2155]: Soliciting pool server 14.139.60.103 Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:52:08 volumio ntpd[2155]: Soliciting pool server 95.216.192.15 Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:52:08 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin bluetooth to MyMusic Plugins Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin multiroom to MyMusic Plugins Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin metavolumio to MyMusic Plugins Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin cd_controller to MyMusic Plugins Apr 27 07:52:09 volumio ntpd[2155]: Soliciting pool server 172.105.60.167 Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 27 07:52:09 volumio ntpd[2155]: Soliciting pool server 192.46.210.39 Apr 27 07:52:09 volumio volumio[1330]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 27 07:52:09 volumio volumio[1330]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 27 07:52:10 volumio volumio[1330]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 27 07:52:10 volumio volumio[1330]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 27 07:52:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:10 volumio volumio[1330]: info: Starting MyVolumio Remote Streaming Endpoints Apr 27 07:52:10 volumio volumio[1330]: info: MyVolumio login type: Token Apr 27 07:52:10 volumio volumio[1330]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 27 07:52:10 volumio volumio[1330]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 27 07:52:10 volumio ntpd[2155]: Soliciting pool server 192.46.215.60 Apr 27 07:52:10 volumio ntpd[2155]: Soliciting pool server 157.245.102.2 Apr 27 07:52:11 volumio volumio[1330]: info: Starting Streaming Service Transparent Proxy Apr 27 07:52:11 volumio volumio[1330]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 27 07:52:11 volumio volumio[1330]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 27 07:52:11 volumio volumio[1330]: info: Streaming services startup Apr 27 07:52:11 volumio volumio[1330]: info: Starting Streaming Daemon Apr 27 07:52:11 volumio sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 27 07:52:11 volumio sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:52:11 volumio sudo[2191]: pam_unix(sudo:session): session closed for user root Apr 27 07:52:11 volumio volumio[1330]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 27 07:52:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:52:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 27 07:52:11 volumio volumio[1330]: error: Cannot start Volumio Streaming Daemon Apr 27 07:52:11 volumio volumio[1330]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 27 07:52:11 volumio volumio[1330]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 27 07:52:11 volumio ntpd[2155]: Soliciting pool server 162.159.200.1 Apr 27 07:52:11 volumio volumio[1330]: STREAMING PROXY: Starting server on port 3245 Apr 27 07:52:11 volumio volumio[1330]: Node JS runtime: 14 Apr 27 07:52:12 volumio volumio[1330]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 27 07:52:12 volumio ntpd[2155]: Soliciting pool server 95.216.144.226 Apr 27 07:52:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:52:13 volumio ntpd[2155]: Soliciting pool server 2001:1600:4:1::f Apr 27 07:52:14 volumio volumio[1330]: info: MyVolumio token set successfully Apr 27 07:52:14 volumio volumio[1330]: info: MYVOLUMIO: Adding device Apr 27 07:52:14 volumio volumio[1330]: info: MYVOLUMIO: Evaluating Server Apr 27 07:52:14 volumio systemd[1]: systemd-timedated.service: Succeeded. Apr 27 07:52:15 volumio volumio[1330]: info: MyVolumio status changed Apr 27 07:52:15 volumio volumio[1330]: info: Streaming services startup Apr 27 07:52:15 volumio volumio[1330]: info: Starting Streaming Daemon Apr 27 07:52:15 volumio volumio[1330]: info: Removing browser output: myVolumio user plan is not superstar Apr 27 07:52:15 volumio volumio[1330]: info: Removing audio output: Apr 27 07:52:15 volumio volumio[1330]: info: Stoppping Tunnel 1 Apr 27 07:52:15 volumio sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 27 07:52:15 volumio sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:52:15 volumio sudo[2217]: pam_unix(sudo:session): session closed for user root Apr 27 07:52:15 volumio volumio[1330]: error: Cannot start Volumio Streaming Daemon Apr 27 07:52:15 volumio volumio[1330]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 27 07:52:15 volumio volumio[1330]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 27 07:52:15 volumio sudo[2220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 27 07:52:15 volumio sudo[2220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:52:15 volumio sudo[2220]: pam_unix(sudo:session): session closed for user root Apr 27 07:52:15 volumio volumio[1330]: info: Remote SSH Stopped Apr 27 07:52:15 volumio volumio[1330]: info: Setting Geolocation for MyVolumio to as2 Apr 27 07:52:15 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:15 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:15 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:16 volumio volumio[1330]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 27 07:52:16 volumio volumio[1330]: info: Updating MyVolumio device info Apr 27 07:52:16 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:16 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:17 volumio volumio[1330]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 27 07:52:17 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:52:20 volumio volumio[1330]: info: MYVOLUMIO: Adding device Apr 27 07:52:20 volumio volumio[1330]: info: MYVOLUMIO: Evaluating Server Apr 27 07:52:20 volumio volumio[1330]: info: Setting Geolocation for MyVolumio to as2 Apr 27 07:52:20 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:20 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:20 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 07:52:21 volumio volumio-remote-updater[907]: No test mode Apr 27 07:52:21 volumio volumio-remote-updater[907]: No alpha test mode Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:52:21 volumio volumio[1330]: info: Updating MyVolumio device info Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:21 volumio volumio[1330]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 27 07:52:21 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 27 07:52:22 volumio volumio[1330]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Apr 27 07:52:22 volumio volumio[1330]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Apr 27 07:52:25 volumio volumio[1330]: info: CALLMETHOD: audio_interface fusiondsp disableeffect undefined Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , disableeffect Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 07:52:25 volumio volumio[1330]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 07:52:25 volumio volumio[1330]: info: FusionDsp - Effects disabled Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::Close All Modals sent Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:52:25 volumio volumio[1330]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Apr 27 07:52:25 volumio volumio[1330]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 27 07:52:25 volumio volumio[1330]: info: FusionDsp - cool Apr 27 07:52:25 volumio volumio[1330]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,cool.json Apr 27 07:52:25 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:52:25 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:52:29 volumio volumio[1330]: info: CALLMETHOD: audio_interface fusiondsp enableeffect undefined Apr 27 07:52:29 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , enableeffect Apr 27 07:52:29 volumio volumio[1330]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 07:52:29 volumio volumio[1330]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 27 07:52:29 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:52:29 volumio volumio[1330]: info: CoreCommandRouter::Close All Modals sent Apr 27 07:52:29 volumio volumio[1330]: info: FusionDsp - cool Apr 27 07:52:29 volumio volumio[1330]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,cool.json Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:52:30 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 07:52:31 volumio volumio[1330]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 27 07:52:31 volumio volumio[1330]: info: Completed starting MyVolumio Plugin Apr 27 07:52:31 volumio volumio[1330]: [Metrics] CommandRouter: 48s 323.15ms Apr 27 07:52:31 volumio volumio[1330]: info: CoreCommandRouter::volumiosetStartupVolume Apr 27 07:52:31 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:52:31 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:52:31 volumio volumio[1330]: info: CoreCommandRouter::Close All Modals sent Apr 27 07:52:31 volumio volumio[1330]: info: CoreCommandRouter::Close All Modals sent Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 07:52:31 volumio volumio[1330]: info: camilladsp stopping service pid 1860... Apr 27 07:52:31 volumio volumio[1330]: info: camilladsp service terminated, instance 1 Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 07:52:31 volumio volumio[1330]: info: camilladsp service started and running in background, instance 1 Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 27 07:52:31 volumio volumio[1330]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 27 07:52:31 volumio volumio[1330]: error: FusionDsp - WebSocket error: [object Object] Apr 27 07:52:32 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 27 07:52:32 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 07:52:32 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 27 07:52:38 volumio volumio[1330]: info: BOOT COMPLETED Apr 27 07:52:41 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:52:41 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:52:53 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 07:52:53 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 27 07:52:53 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:52:53 volumio volumio[1330]: info: FusionDsp - cool Apr 27 07:52:53 volumio volumio[1330]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,cool.json Apr 27 07:53:00 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:53:00 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 07:53:06 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 07:53:13 volumio volumio[1330]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 27 07:53:13 volumio volumio[1330]: info: Preparing to save Alsa Options, stopping services first Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:53:13 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::volumioPause Apr 27 07:53:13 volumio volumio[1330]: info: CoreStateMachine::pause Apr 27 07:53:13 volumio volumio[1330]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"E30 II"}} Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 07:53:13 volumio volumio[1330]: info: Setting mixer E30 II for card E30 II Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 27 07:53:13 volumio volumio[1330]: info: Updating Volume Controller Parameters: Device: 5 Name: E30 II Mixer: E30 II Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 27 07:53:13 volumio volumio[1330]: info: Disabling external Volume Control Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:13 volumio volumio[1330]: info: Preparing to generate the ALSA configuration file Apr 27 07:53:13 volumio volumio[1330]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 27 07:53:13 volumio volumio[1330]: info: Reading ALSA contributions from plugins. Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 07:53:13 volumio volumio[1330]: info: VolumeController:: Volume=100 Mute =false Apr 27 07:53:13 volumio volumio[1330]: info: CoreStateMachine::pushState Apr 27 07:53:13 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState Apr 27 07:53:13 volumio volumio[1330]: info: Asound.conf file written Apr 27 07:53:13 volumio sudo[2460]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 27 07:53:13 volumio sudo[2460]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:13 volumio sudo[2460]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:13 volumio volumio[1330]: info: Output device has changed, restarting MPD Apr 27 07:53:13 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 07:53:13 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:13 volumio volumio[1330]: info: Output device has changed, restarting Shairport Sync Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:13 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:13 volumio sudo[2467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 07:53:13 volumio sudo[2467]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:13 volumio systemd[1]: Stopping Music Player Daemon... Apr 27 07:53:13 volumio systemd[1]: mpd.service: Succeeded. Apr 27 07:53:13 volumio systemd[1]: Stopped Music Player Daemon. Apr 27 07:53:13 volumio volumio[1330]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 07:53:13 volumio volumio[1330]: info: Output device has changed, restarting MPD Apr 27 07:53:13 volumio systemd[1]: Starting Music Player Daemon... Apr 27 07:53:13 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 27 07:53:13 volumio volumio[1330]: info: Output device has changed, restarting Shairport Sync Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:13 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:13 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:13 volumio sudo[2477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 27 07:53:13 volumio sudo[2477]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 27 07:53:13 volumio systemd[1]: mpd.service: Succeeded. Apr 27 07:53:13 volumio systemd[1]: Stopped Music Player Daemon. Apr 27 07:53:13 volumio systemd[1]: Starting Music Player Daemon... Apr 27 07:53:13 volumio volumio[1330]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 27 07:53:13 volumio volumio[1330]: info: MPD Permissions set Apr 27 07:53:13 volumio volumio[1330]: info: MPD Permissions set Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio sudo[2483]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 27 07:53:14 volumio sudo[2483]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:14 volumio sudo[2483]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 27 07:53:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 07:53:14 volumio volumio[1330]: info: Starting Shairport Sync Apr 27 07:53:14 volumio volumio[1330]: info: Starting Shairport Sync Apr 27 07:53:14 volumio sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 07:53:14 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 07:53:14 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 27 07:53:14 volumio sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 27 07:53:14 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 07:53:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 07:53:14 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:14 volumio volumio[1330]: info: Shairport-Sync Started Apr 27 07:53:14 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 27 07:53:14 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Apr 27 07:53:14 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 27 07:53:14 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 27 07:53:14 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 27 07:53:14 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:14 volumio volumio[1330]: info: Shairport-Sync Started Apr 27 07:53:14 volumio mpd[2492]: Apr 27 07:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 27 07:53:14 volumio systemd[1]: Started Music Player Daemon. Apr 27 07:53:14 volumio sudo[2467]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:14 volumio sudo[2477]: pam_unix(sudo:session): session closed for user root Apr 27 07:53:14 volumio volumio[1330]: error: MPD error: The expression evaluated to a falsy value: Apr 27 07:53:14 volumio volumio[1330]: assert.ok(self.idling) Apr 27 07:53:14 volumio volumio[1330]: error: The expression evaluated to a falsy value: Apr 27 07:53:14 volumio volumio[1330]: assert.ok(self.idling) Apr 27 07:53:14 volumio volumio[1330]: error: updateQueue error: null Apr 27 07:53:17 volumio volumio[1330]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 27 07:53:17 volumio volumio[1330]: info: CoreStateMachine::getcurrentVolume Apr 27 07:53:17 volumio volumio[1330]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 07:53:17 volumio volumio[1330]: info: VolumeController:: Volume=100 Mute =false Apr 27 07:53:17 volumio volumio[1330]: info: CoreStateMachine::pushState Apr 27 07:53:17 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:17 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState Apr 27 07:53:17 volumio volumio[1330]: info: CoreStateMachine::updateTrackBlock Apr 27 07:53:17 volumio volumio[1330]: info: CorePlayQueue::getTrackBlock Apr 27 07:53:17 volumio volumio[1330]: info: CoreCommandRouter::volumioRetrievevolume Apr 27 07:53:17 volumio volumio[1330]: info: VolumeController:: Volume=100 Mute =false Apr 27 07:53:17 volumio volumio[1330]: info: CoreStateMachine::pushState Apr 27 07:53:17 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:17 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState Apr 27 07:53:29 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState Apr 27 07:53:29 volumio volumio[1330]: info: CorePlayQueue::getTrack 0 Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: info: Retrieving Cloud Streaming UI Apr 27 07:53:33 volumio volumio[1330]: info: Getting Tidal Cloud Configuration Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: info: Getting Qobuz Cloud Configuration Apr 27 07:53:33 volumio volumio[1330]: info: Asking plugin for UI Config Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: info: Getting Spotify Cloud Configuration Apr 27 07:53:33 volumio volumio[1330]: info: Asking plugin for UI Config Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: info: Saving Spotify Acccount Apr 27 07:53:33 volumio volumio[1330]: info: Got Tidal Cloud Configuration Apr 27 07:53:33 volumio volumio[1330]: info: Got it Apr 27 07:53:33 volumio volumio[1330]: info: Got it Apr 27 07:53:33 volumio volumio[1330]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 07:53:33 volumio volumio[1330]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:53:33 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources Apr 27 07:53:34 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:53:34 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 27 07:53:37 volumio volumio[1330]: info: Disabling MyMusic plugin upnp Apr 27 07:53:37 volumio sudo[2614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Apr 27 07:53:37 volumio sudo[2614]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 07:53:37 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Apr 27 07:53:37 volumio volumio[1330]: error: Upnp client error: Error: This socket has been ended by the other party Apr 27 07:53:38 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:53:42 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 27 07:53:43 volumio volumio[1330]: info: Enabling MyMusic plugin upnp Apr 27 07:53:43 volumio volumio[1330]: info: Enabling plugin upnp Apr 27 07:53:43 volumio volumio[1330]: info: Loading plugin "upnp"... Apr 27 07:53:43 volumio volumio[1330]: info: [1745720623767] Starting Upmpd Daemon Apr 27 07:53:43 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 27 07:53:43 volumio volumio[1330]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 07:53:43 volumio volumio[1330]: Error: listen EADDRINUSE: address already in use :::6599 Apr 27 07:53:43 volumio volumio[1330]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Apr 27 07:53:43 volumio volumio[1330]: at listenInCluster (net.js:1379:12) Apr 27 07:53:43 volumio volumio[1330]: at Server.listen (net.js:1465:7) Apr 27 07:53:43 volumio volumio[1330]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Apr 27 07:53:43 volumio volumio[1330]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Apr 27 07:53:43 volumio volumio[1330]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Apr 27 07:53:43 volumio volumio[1330]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Apr 27 07:53:43 volumio volumio[1330]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Apr 27 07:53:43 volumio volumio[1330]: code: 'EADDRINUSE', Apr 27 07:53:43 volumio volumio[1330]: errno: -98, Apr 27 07:53:43 volumio volumio[1330]: syscall: 'listen', Apr 27 07:53:43 volumio volumio[1330]: address: '::', Apr 27 07:53:43 volumio volumio[1330]: port: 6599 Apr 27 07:53:43 volumio volumio[1330]: } Apr 27 07:53:43 volumio volumio[1330]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 07:53:44 volumio sudo[2654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-27 07:52 Apr 27 07:53:44 volumio sudo[2654]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"