-- Logs begin at Sat 2024-04-06 16:14:51 +04, end at Sat 2024-04-06 16:17:41 +04. -- Apr 06 16:16:01 volumio volumio[973]: error: Plugin system_controller system failed to complete 'onVolumioStart' in a timely fashion Apr 06 16:16:01 volumio volumio[973]: info: Preparing to generate the ALSA configuration file Apr 06 16:16:01 volumio volumio[973]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 06 16:16:01 volumio volumio[973]: info: Reading ALSA contributions from plugins. Apr 06 16:16:01 volumio volumio[973]: info: Asound.conf file unchanged, so no further update is needed Apr 06 16:16:01 volumio volumio[973]: info: Output device has changed, restarting MPD Apr 06 16:16:01 volumio volumio[973]: info: Output device has changed, restarting Shairport Sync Apr 06 16:16:01 volumio sudo[1141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:01 volumio sudo[1141]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:01 volumio sudo[1141]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:01 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 06 16:16:01 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:01 volumio volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 16:16:01 volumio volumio[973]: info: ___________ START PLUGINS ___________ Apr 06 16:16:01 volumio volumio[973]: info: ControllerMpd::onStart: Initializing MPD Apr 06 16:16:01 volumio volumio[973]: info: Creating MPD Configuration file Apr 06 16:16:01 volumio systemd[1]: Listening on mpd.socket. Apr 06 16:16:01 volumio systemd[1]: Starting Music Player Daemon... Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 16:16:01 volumio volumio[973]: info: [1712405761712] CoreMusicLibrary::Adding element Медиа-серверы Apr 06 16:16:01 volumio sudo[1151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:16:01 volumio sudo[1151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:01 volumio sudo[1151]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:01 volumio sudo[1154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 06 16:16:01 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 06 16:16:01 volumio systemd[1]: mpd.service: Succeeded. Apr 06 16:16:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 06 16:16:01 volumio systemd[1]: Starting Music Player Daemon... Apr 06 16:16:01 volumio volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 16:16:01 volumio volumio[973]: info: [1712405761862] CoreMusicLibrary::Adding element Last_100 Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 16:16:01 volumio volumio[973]: info: [1712405761867] CoreMusicLibrary::Adding element Webradio Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:16:01 volumio volumio[973]: info: [now-playing-config] Config is up to date. Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:16:01 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:01 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:01 volumio volumio[973]: info: Loading i18n strings for locale ru Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:01 volumio volumio[973]: info: FusionDsp - mixtype--------------------- Hardware Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 06 16:16:01 volumio volumio[973]: info: [1712405761980] CoreMusicLibrary::Adding element 80s80s Radio Apr 06 16:16:01 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:16:01 volumio volumio[973]: Cannot find translation for source 80s80s Radio Apr 06 16:16:01 volumio volumio[973]: info: Stopping AccessToken refresher cron for DMD2 Music Apr 06 16:16:02 volumio volumio[973]: info: AccessToken refresher cron started for DMD2 Music Apr 06 16:16:02 volumio volumio[973]: info: Volumio Calling Home Apr 06 16:16:02 volumio volumio[973]: info: [now-playing-app] App is listening on port 4004. Apr 06 16:16:02 volumio volumio[973]: info: Preparing to generate the ALSA configuration file Apr 06 16:16:02 volumio volumio[973]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Apr 06 16:16:02 volumio volumio[973]: info: Reading ALSA contributions from plugins. Apr 06 16:16:02 volumio volumio[973]: info: MPD Permissions set Apr 06 16:16:02 volumio volumio[973]: info: MPD Permissions set Apr 06 16:16:02 volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Apr 06 16:16:02 volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: Volumio called home Apr 06 16:16:02 volumio volumio[973]: info: Starting Shairport Sync Apr 06 16:16:02 volumio volumio[973]: info: Starting Shairport Sync Apr 06 16:16:02 volumio volumio[973]: info: Starting Shairport Sync Apr 06 16:16:02 volumio sudo[1176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 16:16:02 volumio sudo[1176]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 06 16:16:02 volumio sudo[1178]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 16:16:02 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 06 16:16:02 volumio sudo[1178]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:02 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 16:16:02 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:02 volumio sudo[1176]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:02 volumio volumio[973]: info: Asound.conf file unchanged, so no further update is needed Apr 06 16:16:02 volumio volumio[973]: info: Output device has changed, restarting MPD Apr 06 16:16:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 06 16:16:02 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 06 16:16:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:02 volumio volumio[973]: info: Output device has changed, restarting Shairport Sync Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:02 volumio sudo[1188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 06 16:16:02 volumio sudo[1188]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:02 volumio sudo[1188]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:02 volumio sudo[1191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 06 16:16:02 volumio sudo[1191]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:02 volumio sudo[1178]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:02 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:02 volumio volumio[973]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 06 16:16:02 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Apr 06 16:16:02 volumio systemd[1]: mpd.service: Succeeded. Apr 06 16:16:02 volumio volumio[973]: info: Shairport-Sync Started Apr 06 16:16:02 volumio systemd[1]: Stopped Music Player Daemon. Apr 06 16:16:02 volumio volumio[973]: Error adding Membership: Error: addMembership EINVAL Apr 06 16:16:02 volumio systemd[1]: Starting Music Player Daemon... Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:02 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:02 volumio volumio[973]: info: MPD Permissions set Apr 06 16:16:02 volumio volumio[973]: info: Shairport-Sync Started Apr 06 16:16:02 volumio volumio[973]: info: Shairport-Sync Started Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:02 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:03 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:03 volumio volumio[973]: info: Starting Shairport Sync Apr 06 16:16:03 volumio sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 06 16:16:03 volumio sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 06 16:16:03 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 06 16:16:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 06 16:16:03 volumio sudo[1205]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:03 volumio volumio[973]: info: Shairport-Sync Started Apr 06 16:16:04 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:04 volumio volumio[973]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 96000 192000 <-AAAAAAAAAAAAA Apr 06 16:16:04 volumio volumio[973]: info: FusionDsp loaded Apr 06 16:16:04 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:16:04 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 06 16:16:04 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:04 volumio volumio[973]: info: FusionDsp - Reporting Fusion DSP Enabled Apr 06 16:16:04 volumio volumio[973]: info: Adding Signal Path Element [object Object] Apr 06 16:16:04 volumio volumio[973]: info: Adding fusiondspeq DSP Signal Path Element Apr 06 16:16:04 volumio volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 06 16:16:04 volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Apr 06 16:16:04 volumio systemd[1]: Started FusionDsp Daemon. Apr 06 16:16:04 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:04 volumio mpd[1198]: Apr 06 16:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 06 16:16:05 volumio volumio[973]: info: FusionDsp - eq1: Apr 06 16:16:05 volumio volumio[973]: type: Biquad Apr 06 16:16:05 volumio volumio[973]: parameters: Apr 06 16:16:05 volumio volumio[973]: type: Lowshelf Apr 06 16:16:05 volumio volumio[973]: freq: 185 Apr 06 16:16:05 volumio volumio[973]: q: 0.82 Apr 06 16:16:05 volumio volumio[973]: gain: 0 Apr 06 16:16:05 volumio volumio[973]: eq2: Apr 06 16:16:05 volumio volumio[973]: type: Biquad Apr 06 16:16:05 volumio volumio[973]: parameters: Apr 06 16:16:05 volumio volumio[973]: type: Peaking Apr 06 16:16:05 volumio volumio[973]: freq: 1300 Apr 06 16:16:05 volumio volumio[973]: q: 0.4 Apr 06 16:16:05 volumio volumio[973]: gain: 0 Apr 06 16:16:05 volumio volumio[973]: eq3: Apr 06 16:16:05 volumio volumio[973]: type: Biquad Apr 06 16:16:05 volumio volumio[973]: parameters: Apr 06 16:16:05 volumio volumio[973]: type: Highshelf Apr 06 16:16:05 volumio volumio[973]: freq: 5500 Apr 06 16:16:05 volumio volumio[973]: q: 0.82 Apr 06 16:16:05 volumio volumio[973]: gain: 0 Apr 06 16:16:05 volumio volumio[973]: error: FusionDsp - WebSocket error: [object Object] Apr 06 16:16:05 volumio systemd[1]: Started Music Player Daemon. Apr 06 16:16:05 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:05 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:05 volumio sudo[1191]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:05 volumio volumio[973]: info: Completed starting Core Plugins Apr 06 16:16:05 volumio volumio[973]: info: ------------------------------------------- Apr 06 16:16:05 volumio volumio[973]: info: ----- MyVolumio plugins startup ---- Apr 06 16:16:05 volumio volumio[973]: info: ------------------------------------------- Apr 06 16:16:05 volumio volumio[973]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 06 16:16:05 volumio volumio[973]: info: MPD running with PID1198 Apr 06 16:16:05 volumio volumio[973]: ,establishing connection Apr 06 16:16:05 volumio volumio[973]: error: MPD error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: MPD error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: MPD error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: The expression evaluated to a falsy value: Apr 06 16:16:05 volumio volumio[973]: assert.ok(self.idling) Apr 06 16:16:05 volumio volumio[973]: error: updateQueue error: null Apr 06 16:16:06 volumio ntpd[711]: Soliciting pool server 162.159.200.123 Apr 06 16:16:07 volumio ntpd[711]: Soliciting pool server 85.21.78.23 Apr 06 16:16:07 volumio ntpd[711]: Soliciting pool server 193.192.36.3 Apr 06 16:16:08 volumio ntpd[711]: Soliciting pool server 89.179.240.219 Apr 06 16:16:08 volumio ntpd[711]: Soliciting pool server 85.21.78.91 Apr 06 16:16:08 volumio ntpd[711]: Soliciting pool server 192.36.143.130 Apr 06 16:16:09 volumio ntpd[711]: Soliciting pool server 162.159.200.1 Apr 06 16:16:09 volumio ntpd[711]: Soliciting pool server 91.209.94.10 Apr 06 16:16:09 volumio ntpd[711]: Soliciting pool server 82.142.168.18 Apr 06 16:16:09 volumio ntpd[711]: Soliciting pool server 217.70.19.12 Apr 06 16:16:10 volumio ntpd[711]: Soliciting pool server 46.146.231.187 Apr 06 16:16:10 volumio ntpd[711]: Soliciting pool server 83.143.51.50 Apr 06 16:16:10 volumio ntpd[711]: Soliciting pool server 188.225.9.167 Apr 06 16:16:11 volumio ntpd[711]: Soliciting pool server 91.206.16.3 Apr 06 16:16:11 volumio ntpd[711]: Soliciting pool server 2a00:ab00:203:9::1000:6 Apr 06 16:16:11 volumio ntpd[711]: Soliciting pool server 95.163.183.223 Apr 06 16:16:13 volumio volumio[1104]: Generating RSA private key, 4096 bit long modulus (2 primes) Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e88fbbd5 does not match aorg 0000000000.00000000 from server@217.70.19.12 xmt 0xe9bbbba1.038800d6 Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e8829f95 does not match aorg 0000000000.00000000 from server@95.163.183.223 xmt 0xe9bbbba1.03ff823d Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e89248d8 does not match aorg 0000000000.00000000 from server@82.142.168.18 xmt 0xe9bbbba1.038bee48 Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e899179c does not match aorg 0000000000.00000000 from server@192.36.143.130 xmt 0xe9bbbba1.0599e1e2 Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e89b80ec does not match aorg 0000000000.00000000 from server@193.192.36.3 xmt 0xe9bbbba1.0b871408 Apr 06 16:16:33 volumio ntpd[711]: receive: Unexpected origin timestamp 0xe9bbbb8e.e88b9dcb does not match aorg 0000000000.00000000 from server@188.225.9.167 xmt 0xe9bbbba1.07714d5d Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin bluetooth to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin multiroom to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin metavolumio to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin cd_controller to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin smart_inputs to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: Adding plugin tidalconnect to MyMusic Plugins Apr 06 16:16:35 volumio volumio[973]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Apr 06 16:16:36 volumio volumio[973]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Apr 06 16:16:36 volumio volumio[973]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Apr 06 16:16:36 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:36 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:36 volumio volumio[973]: info: Starting MyVolumio Remote Streaming Endpoints Apr 06 16:16:37 volumio volumio[973]: info: MyVolumio login type: Token Apr 06 16:16:37 volumio volumio[973]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Apr 06 16:16:37 volumio volumio[973]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Apr 06 16:16:38 volumio volumio[973]: info: Starting Streaming Service Transparent Proxy Apr 06 16:16:38 volumio volumio[973]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Apr 06 16:16:38 volumio volumio[973]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Apr 06 16:16:38 volumio volumio[973]: info: Streaming services startup Apr 06 16:16:38 volumio volumio[973]: info: Starting Streaming Daemon Apr 06 16:16:38 volumio sudo[1249]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 06 16:16:38 volumio sudo[1249]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:38 volumio sudo[1249]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:38 volumio volumio[973]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Apr 06 16:16:38 volumio volumio[973]: error: Cannot start Volumio Streaming Daemon Apr 06 16:16:38 volumio volumio[973]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 06 16:16:38 volumio volumio[973]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 06 16:16:39 volumio volumio[973]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Apr 06 16:16:39 volumio volumio[973]: STREAMING PROXY: Starting server on port 3245 Apr 06 16:16:39 volumio volumio[973]: Node JS runtime: 14 Apr 06 16:16:40 volumio volumio[973]: info: MyVolumio token set successfully Apr 06 16:16:40 volumio volumio[973]: info: MYVOLUMIO: Adding device Apr 06 16:16:40 volumio volumio[973]: info: MYVOLUMIO: Evaluating Server Apr 06 16:16:40 volumio volumio[973]: info: MyVolumio status changed Apr 06 16:16:40 volumio volumio[973]: info: Streaming services startup Apr 06 16:16:40 volumio volumio[973]: info: Starting Streaming Daemon Apr 06 16:16:40 volumio volumio[973]: info: Removing browser output: myVolumio user plan is not superstar Apr 06 16:16:40 volumio volumio[973]: info: Removing audio output: Apr 06 16:16:40 volumio volumio[973]: info: Stoppping Tunnel 1 Apr 06 16:16:40 volumio sudo[1274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 06 16:16:40 volumio sudo[1274]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:40 volumio sudo[1274]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:40 volumio sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Apr 06 16:16:40 volumio sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:16:40 volumio volumio[973]: error: Cannot start Volumio Streaming Daemon Apr 06 16:16:40 volumio volumio[973]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 06 16:16:40 volumio volumio[973]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 06 16:16:40 volumio sudo[1277]: pam_unix(sudo:session): session closed for user root Apr 06 16:16:40 volumio volumio[973]: info: Remote SSH Stopped Apr 06 16:16:41 volumio volumio[973]: info: Setting Geolocation for MyVolumio to eu4 Apr 06 16:16:41 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:41 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:41 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:41 volumio volumio[973]: info: Successfully Added MyVolumio device Apr 06 16:16:42 volumio volumio[973]: info: Updating MyVolumio device info Apr 06 16:16:42 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:42 volumio volumio[973]: info: Successfully Updated MyVolumio device Apr 06 16:16:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:16:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 06 16:16:50 volumio volumio-remote-updater[558]: No test mode Apr 06 16:16:50 volumio volumio-remote-updater[558]: No alpha test mode Apr 06 16:16:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 06 16:16:50 volumio volumio[973]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 06 16:16:50 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 06 16:16:51 volumio volumio[1104]: .....................................................................................................................................................++++ Apr 06 16:16:51 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:16:52 volumio volumio[973]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Apr 06 16:16:52 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:16:52 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:54 volumio volumio[1104]: ........................++++ Apr 06 16:16:54 volumio volumio[1104]: e is 65537 (0x010001) Apr 06 16:16:54 volumio volumio[1104]: writing RSA key Apr 06 16:16:56 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:56 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:56 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 06 16:16:56 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 06 16:16:57 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 06 16:16:57 volumio volumio[973]: info: CoreCommandRouter::volumioGetBrowseSources Apr 06 16:16:57 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:16:58 volumio volumio[973]: error: MyVolumio Plugin failed to authenticate in a timely fashion Apr 06 16:16:58 volumio volumio[973]: info: Completed starting MyVolumio Plugin Apr 06 16:16:58 volumio volumio[973]: info: BOOT COMPLETED Apr 06 16:16:58 volumio volumio[973]: [Metrics] CommandRouter: 83s 987.13ms Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::volumiosetStartupVolume Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 06 16:16:58 volumio volumio[973]: info: VolumeController:: Setting startup Volume 50 Apr 06 16:16:58 volumio volumio[973]: info: VolumeController::SetAlsaVolume50 Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::Close All Modals sent Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::Close All Modals sent Apr 06 16:16:58 volumio volumio[973]: info: CoreStateMachine::pushState Apr 06 16:16:58 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 06 16:16:58 volumio volumio[973]: info: CoreCommandRouter::volumioPushState Apr 06 16:16:58 volumio volumio[973]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 06 16:16:59 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 06 16:16:59 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 06 16:16:59 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 06 16:16:59 volumio volumio[973]: info: FusionDsp - eq1: Apr 06 16:16:59 volumio volumio[973]: type: Biquad Apr 06 16:16:59 volumio volumio[973]: parameters: Apr 06 16:16:59 volumio volumio[973]: type: Lowshelf Apr 06 16:16:59 volumio volumio[973]: freq: 185 Apr 06 16:16:59 volumio volumio[973]: q: 0.82 Apr 06 16:16:59 volumio volumio[973]: gain: 0 Apr 06 16:16:59 volumio volumio[973]: eq2: Apr 06 16:16:59 volumio volumio[973]: type: Biquad Apr 06 16:16:59 volumio volumio[973]: parameters: Apr 06 16:16:59 volumio volumio[973]: type: Peaking Apr 06 16:16:59 volumio volumio[973]: freq: 1300 Apr 06 16:16:59 volumio volumio[973]: q: 0.4 Apr 06 16:16:59 volumio volumio[973]: gain: 0 Apr 06 16:16:59 volumio volumio[973]: eq3: Apr 06 16:16:59 volumio volumio[973]: type: Biquad Apr 06 16:16:59 volumio volumio[973]: parameters: Apr 06 16:16:59 volumio volumio[973]: type: Highshelf Apr 06 16:16:59 volumio volumio[973]: freq: 5500 Apr 06 16:16:59 volumio volumio[973]: q: 0.82 Apr 06 16:16:59 volumio volumio[973]: gain: 0 Apr 06 16:16:59 volumio volumio[973]: error: FusionDsp - WebSocket error: [object Object] Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:13 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:13 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:13 volumio volumio[973]: verbose: New Socket.io Connection to 192.168.50.7 from 192.168.50.212 UA: okhttp/4.9.2 Total Clients: 9 Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:13 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:17:13 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:15 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:15 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:15 volumio volumio[973]: verbose: New Socket.io Connection to 192.168.50.75 from 192.168.50.212 UA: okhttp/4.9.2 Total Clients: 10 Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:15 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 06 16:17:15 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 06 16:17:17 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 06 16:17:17 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:17:17 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root Apr 06 16:17:17 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 06 16:17:17 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 06 16:17:17 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Apr 06 16:17:17 volumio volumio[973]: verbose: New Socket.io Connection to 192.168.50.7 from 192.168.50.212 UA: okhttp/4.9.2 Total Clients: 8 Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetVisibleSources Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:17 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 06 16:17:17 volumio volumio[973]: info: Received Get System Info Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:17 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:17 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:17 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:17 volumio volumio[973]: info: Listing playlists Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:17:17 volumio volumio[973]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 06 16:17:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:17:19 volumio volumio[973]: info: Received Get System Info Apr 06 16:17:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:19 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:19 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:19 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:19 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 06 16:17:20 volumio volumio[973]: info: Received Get System Info Apr 06 16:17:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:20 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:20 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:20 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:20 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:21 volumio volumio[973]: info: CoreCommandRouter::volumioGetQueue Apr 06 16:17:21 volumio volumio[973]: info: CoreStateMachine::getQueue Apr 06 16:17:21 volumio volumio[973]: info: CorePlayQueue::getQueue Apr 06 16:17:26 volumio kernel: hwmon hwmon1: Undervoltage detected! Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 06 16:17:35 volumio volumio[973]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 06 16:17:35 volumio volumio[973]: info: Received Get System Version Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 06 16:17:35 volumio volumio[973]: info: Received Get System Info Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 06 16:17:35 volumio volumio[973]: info: Discovery: Getting this device information Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::volumioGetState Apr 06 16:17:35 volumio volumio[973]: info: CorePlayQueue::getTrack 0 Apr 06 16:17:35 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 06 16:17:36 volumio volumio[973]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 06 16:17:36 volumio volumio[973]: info: CURURI: music-library/USB/MUSIC/BAD COMPANY - DISCOGRAPHY Apr 06 16:17:36 volumio volumio[973]: info: Preload queue cleared Apr 06 16:17:38 volumio ntpd[711]: Soliciting pool server 2a00:1390:5:722b:1337:c0de:4:11fe Apr 06 16:17:38 volumio ntpd[711]: Soliciting pool server 192.36.143.134 Apr 06 16:17:40 volumio volumio[973]: info: Preload queue cleared Apr 06 16:17:40 volumio volumio[973]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 06 16:17:40 volumio volumio[973]: info: CoreStateMachine::ClearQueue Apr 06 16:17:40 volumio volumio[973]: info: CoreStateMachine::stop Apr 06 16:17:40 volumio volumio[973]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 06 16:17:40 volumio volumio[973]: info: CorePlayQueue::clearPlayQueue Apr 06 16:17:40 volumio volumio[973]: info: CorePlayQueue::saveQueue Apr 06 16:17:40 volumio volumio[973]: info: CoreCommandRouter::volumioPushQueue Apr 06 16:17:40 volumio volumio[973]: info: CoreStateMachine::addQueueItems Apr 06 16:17:40 volumio volumio[973]: info: CorePlayQueue::addQueueItems Apr 06 16:17:40 volumio volumio[973]: info: Preload queue cleared Apr 06 16:17:40 volumio volumio[973]: info: Adding Item to queue: music-library/USB/MUSIC/BAD COMPANY - DISCOGRAPHY/Bad Company - FLAC (1974 - 1982) Apr 06 16:17:40 volumio volumio[973]: info: Exploding uri music-library/USB/MUSIC/BAD COMPANY - DISCOGRAPHY/Bad Company - FLAC (1974 - 1982) in service mpd Apr 06 16:17:40 volumio volumio[973]: info: ALBUMART /albumart?cacheid=901&web=Bad%20Company%20/Bad%20Company%20(7567-92441-2)/medium&path=%2Fmnt%2FUSB%2FMUSIC%2FBAD%20COMPANY%20-%20DISCOGRAPHY%2FBad%20Company%20-%20FLAC%20(1974%20-%201982)%2F1974%20Bad%20Company%2FBad%20Company%20%20-%20Bad%20Company%20(7567-92441-2).cue&metadata=false Apr 06 16:17:40 volumio volumio[973]: info: URI /mnt/USB/MUSIC/BAD COMPANY - DISCOGRAPHY/Bad Company - FLAC (1974 - 1982)/1974 Bad Company/Bad Company - Bad Company (7567-92441-2).cue Apr 06 16:17:40 volumio volumio[973]: info: ALBUMART /albumart?cacheid=901&web=Bad%20Company%20/Bad%20Company%20(7567-92441-2)/medium&path=%2Fmnt%2FUSB%2FMUSIC%2FBAD%20COMPANY%20-%20DISCOGRAPHY%2FBad%20Company%20-%20FLAC%20(1974%20-%201982)%2F1974%20Bad%20Company%2FBad%20Company%20%20-%20Bad%20Company%20(7567-92441-2).cue&metadata=false Apr 06 16:17:40 volumio volumio[973]: info: URI /mnt/USB/MUSIC/BAD COMPANY - DISCOGRAPHY/Bad Company - FLAC (1974 - 1982)/1974 Bad Company/Bad Company - Bad Company (7567-92441-2).cue Apr 06 16:17:40 volumio volumio[973]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:17:40 volumio volumio[973]: Error: Unable to resolve or reject the same promise twice Apr 06 16:17:40 volumio volumio[973]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 06 16:17:40 volumio volumio[973]: at /volumio/app/plugins/music_service/mpd/index.js:2568:21 Apr 06 16:17:40 volumio volumio[973]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 06 16:17:40 volumio volumio[973]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 06 16:17:40 volumio volumio[973]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 06 16:17:40 volumio volumio[973]: at Socket.emit (events.js:315:20) Apr 06 16:17:40 volumio volumio[973]: at addChunk (internal/streams/readable.js:309:12) Apr 06 16:17:40 volumio volumio[973]: at readableAddChunk (internal/streams/readable.js:280:11) Apr 06 16:17:40 volumio volumio[973]: at Socket.Readable.push (internal/streams/readable.js:223:10) Apr 06 16:17:40 volumio volumio[973]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Apr 06 16:17:40 volumio volumio[973]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 06 16:17:41 volumio sudo[1371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-06 16:16 Apr 06 16:17:41 volumio sudo[1371]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="512433c3e91cdaed7ecd2808b21adeffc2137d7f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 21 Feb 2024 09:37:22 PM CET" VOLUMIO_VERSION="3.631" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fdd9dd1169c06c1ad0a30ddd24c91b6b"