-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-11-01 22:47:13 CET. -- Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin bluetooth to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin multiroom to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin metavolumio to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin cd_controller to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 01 22:46:01 volumio volumio[847]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 01 22:46:01 volumio systemd[1]: systemd-fsckd.service: Succeeded. Nov 01 22:46:01 volumio volumio[1174]: ...............................................++++ Nov 01 22:46:02 volumio volumio[847]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 01 22:46:02 volumio volumio[847]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 01 22:46:02 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:02 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:02 volumio volumio[847]: info: Starting MyVolumio Remote Streaming Endpoints Nov 01 22:46:02 volumio volumio[847]: info: MyVolumio login type: Token Nov 01 22:46:02 volumio volumio[847]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 01 22:46:02 volumio volumio[847]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 01 22:46:03 volumio volumio[847]: info: Starting Streaming Service Transparent Proxy Nov 01 22:46:03 volumio volumio[847]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 01 22:46:03 volumio volumio[847]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 01 22:46:03 volumio volumio[847]: info: Streaming services startup Nov 01 22:46:03 volumio volumio[847]: info: Starting Streaming Daemon Nov 01 22:46:04 volumio sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 22:46:04 volumio sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:04 volumio volumio[847]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 01 22:46:04 volumio sudo[1209]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:04 volumio volumio[847]: error: Cannot start Volumio Streaming Daemon Nov 01 22:46:04 volumio volumio[847]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 01 22:46:04 volumio volumio[847]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 01 22:46:04 volumio sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto musica:/srv/dev-disk-by-uuid-2A6493266492F3B3 /mnt/NAS/musica-nas Nov 01 22:46:04 volumio sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:04 volumio sudo[1217]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:04 volumio sudo[1221]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto MusicaFTP:/srv/dev-disk-by-uuid-2A6493266492F3B3/MUSICA /mnt/NAS/nas-musica Nov 01 22:46:04 volumio volumio[847]: info: Cannot mount NAS musica-nas at system boot, trial number 4 ,retrying in 5 seconds Nov 01 22:46:04 volumio sudo[1221]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:04 volumio sudo[1221]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:04 volumio volumio[847]: info: Cannot mount NAS nas-musica at system boot, trial number 4 ,retrying in 5 seconds Nov 01 22:46:04 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Nov 01 22:46:04 volumio volumio[847]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 01 22:46:05 volumio volumio[847]: STREAMING PROXY: Starting server on port 3245 Nov 01 22:46:05 volumio volumio[847]: Node JS runtime: 14 Nov 01 22:46:05 volumio volumio[847]: info: MyVolumio token set successfully Nov 01 22:46:05 volumio volumio[847]: info: MYVOLUMIO: Adding device Nov 01 22:46:05 volumio volumio[847]: info: MYVOLUMIO: Evaluating Server Nov 01 22:46:05 volumio volumio[847]: info: MyVolumio status changed Nov 01 22:46:05 volumio volumio[847]: info: Streaming services startup Nov 01 22:46:05 volumio volumio[847]: info: Starting Streaming Daemon Nov 01 22:46:05 volumio volumio[847]: info: Removing browser output: myVolumio user plan is not superstar Nov 01 22:46:05 volumio volumio[847]: info: Removing audio output: Nov 01 22:46:05 volumio volumio[847]: info: Stoppping Tunnel 1 Nov 01 22:46:05 volumio sudo[1246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 01 22:46:05 volumio sudo[1246]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:05 volumio sudo[1248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 01 22:46:05 volumio sudo[1248]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:05 volumio sudo[1246]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:05 volumio sudo[1248]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:06 volumio volumio[847]: info: Remote SSH Stopped Nov 01 22:46:06 volumio volumio[847]: error: Cannot start Volumio Streaming Daemon Nov 01 22:46:06 volumio volumio[847]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 01 22:46:06 volumio volumio[847]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 01 22:46:06 volumio volumio[847]: info: Setting Geolocation for MyVolumio to eu8 Nov 01 22:46:06 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:06 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:06 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:07 volumio volumio[847]: info: Successfully Added MyVolumio device Nov 01 22:46:07 volumio volumio[847]: info: Updating MyVolumio device info Nov 01 22:46:07 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:08 volumio volumio[847]: info: Successfully Updated MyVolumio device Nov 01 22:46:09 volumio sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto musica:/srv/dev-disk-by-uuid-2A6493266492F3B3 /mnt/NAS/musica-nas Nov 01 22:46:09 volumio sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:09 volumio sudo[1271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto MusicaFTP:/srv/dev-disk-by-uuid-2A6493266492F3B3/MUSICA /mnt/NAS/nas-musica Nov 01 22:46:09 volumio sudo[1271]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:09 volumio sudo[1267]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:09 volumio volumio[847]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 01 22:46:09 volumio sudo[1271]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:09 volumio volumio[847]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: info: Retrieving Cloud Streaming UI Nov 01 22:46:11 volumio volumio[847]: info: Getting Tidal Cloud Configuration Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: info: Getting Qobuz Cloud Configuration Nov 01 22:46:11 volumio volumio[847]: info: Asking plugin for UI Config Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: info: Getting Spotify Cloud Configuration Nov 01 22:46:11 volumio volumio[847]: info: Asking plugin for UI Config Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: info: Saving Spotify Acccount Nov 01 22:46:11 volumio volumio[847]: info: Got Tidal Cloud Configuration Nov 01 22:46:11 volumio volumio[847]: info: Got it Nov 01 22:46:11 volumio volumio[847]: info: Got it Nov 01 22:46:11 volumio volumio[847]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 01 22:46:11 volumio volumio[847]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:11 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 22:46:14 volumio systemd[1]: systemd-timedated.service: Succeeded. Nov 01 22:46:15 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:16 volumio volumio[1174]: ...................................................................................................................................++++ Nov 01 22:46:16 volumio volumio[1174]: e is 65537 (0x010001) Nov 01 22:46:16 volumio volumio[1174]: writing RSA key Nov 01 22:46:17 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 01 22:46:17 volumio volumio[847]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 34 Nov 01 22:46:17 volumio volumio[847]: info: CoreCommandRouter::volumioGetState Nov 01 22:46:17 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:19 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:20 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 22:46:20 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:20 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 01 22:46:20 volumio volumio-remote-updater[558]: No test mode Nov 01 22:46:20 volumio volumio-remote-updater[558]: No alpha test mode Nov 01 22:46:20 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 01 22:46:20 volumio volumio[847]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 01 22:46:20 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::volumioGetBrowseSources Nov 01 22:46:22 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 01 22:46:23 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:24 volumio volumio[847]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 01 22:46:24 volumio volumio[847]: info: Completed starting MyVolumio Plugin Nov 01 22:46:24 volumio volumio[847]: [Metrics] CommandRouter: 41s 383.44ms Nov 01 22:46:24 volumio volumio[847]: info: CoreCommandRouter::volumiosetStartupVolume Nov 01 22:46:24 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 01 22:46:24 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 01 22:46:24 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:24 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:25 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 01 22:46:25 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 01 22:46:25 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 01 22:46:27 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:31 volumio volumio[847]: info: BOOT COMPLETED Nov 01 22:46:31 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:35 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:39 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:40 volumio ntpd[735]: Soliciting pool server 129.152.16.145 Nov 01 22:46:40 volumio ntpd[735]: Soliciting pool server 193.204.114.232 Nov 01 22:46:41 volumio ntpd[735]: Soliciting pool server 162.159.200.1 Nov 01 22:46:41 volumio ntpd[735]: Soliciting pool server 195.32.70.195 Nov 01 22:46:42 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , editShare Nov 01 22:46:42 volumio volumio[847]: info: Share nas-musica successfully unmounted Nov 01 22:46:42 volumio sudo[1403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto MusicaFTP:/srv/dev-disk-by-uuid-2A6493266492F3B3/MUSICA /mnt/NAS/MusicaFTP Nov 01 22:46:42 volumio sudo[1403]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 01 22:46:42 volumio sudo[1403]: pam_unix(sudo:session): session closed for user root Nov 01 22:46:42 volumio volumio[847]: info: An error occurred mounting the new share. Rolling back configuration Nov 01 22:46:42 volumio ntpd[735]: Soliciting pool server 151.22.209.90 Nov 01 22:46:42 volumio ntpd[735]: Soliciting pool server 172.232.209.103 Nov 01 22:46:42 volumio ntpd[735]: Soliciting pool server 185.19.184.35 Nov 01 22:46:42 volumio ntpd[735]: Soliciting pool server 212.45.144.3 Nov 01 22:46:43 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 22:46:43 volumio ntpd[735]: Soliciting pool server 95.110.135.141 Nov 01 22:46:43 volumio ntpd[735]: Soliciting pool server 85.199.214.99 Nov 01 22:46:43 volumio ntpd[735]: Soliciting pool server 80.211.238.32 Nov 01 22:46:43 volumio ntpd[735]: Soliciting pool server 95.110.254.234 Nov 01 22:46:43 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:44 volumio ntpd[735]: Soliciting pool server 2a00:6d41:10:1194::1 Nov 01 22:46:47 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:49 volumio ntpd[735]: receive: Unexpected origin timestamp 0xecb0fe48.bf05d742 does not match aorg 0000000000.00000000 from server@172.232.209.103 xmt 0xecb0fe49.af9d3a57 Nov 01 22:46:49 volumio ntpd[735]: receive: Unexpected origin timestamp 0xecb0fe48.bf0de154 does not match aorg 0000000000.00000000 from server@129.152.16.145 xmt 0xecb0fe49.b0584fd4 Nov 01 22:46:49 volumio ntpd[735]: receive: Unexpected origin timestamp 0xecb0fe48.befde180 does not match aorg 0000000000.00000000 from server@80.211.238.32 xmt 0xecb0fe49.b0c070f0 Nov 01 22:46:49 volumio ntpd[735]: receive: Unexpected origin timestamp 0xecb0fe48.bf07e282 does not match aorg 0000000000.00000000 from server@195.32.70.195 xmt 0xecb0fe49.b1fc3b4f Nov 01 22:46:49 volumio ntpd[735]: receive: Unexpected origin timestamp 0xecb0fe48.bf0beaae does not match aorg 0000000000.00000000 from server@193.204.114.232 xmt 0xecb0fe49.b2cf592a Nov 01 22:46:52 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand rescan Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand rescan took 7 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 8 milliseconds Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: info: Command Router : Notfying DB Updatetrue Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 46 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 14 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 9 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 8 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 7 milliseconds Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: info: Command Router : Notfying DB Updatefalse Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: info: Command Router : Notfying DB Updatefalse Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:54 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: No code Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:54 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: No code Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:54 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: No code Nov 01 22:46:54 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:54 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:54 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:54 volumio volumio[847]: info: ------------------------------ 170ms Nov 01 22:46:54 volumio volumio[847]: info: ------------------------------ 161ms Nov 01 22:46:54 volumio volumio[847]: info: ------------------------------ 156ms Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: Nov 01 22:46:54 volumio volumio[847]: ---------------------------- MPD announces state update: update Nov 01 22:46:54 volumio volumio[847]: info: ControllerMpd::getState Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::sendMpdCommand status Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 174 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 172 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 20 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 19 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 18 milliseconds Nov 01 22:46:54 volumio volumio[847]: info: sendMpdCommand status took 16 milliseconds Nov 01 22:46:54 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: info: Command Router : Notfying DB Updatefalse Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:55 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: info: Command Router : Notfying DB Updatefalse Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:55 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: info: Command Router : Notfying DB Updatefalse Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::Close All Modals sent Nov 01 22:46:55 volumio volumio[847]: verbose: ControllerMpd::parseState Nov 01 22:46:55 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:55 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: No code Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:55 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: No code Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: ControllerMpd::pushState Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::servicePushState Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Nov 01 22:46:55 volumio volumio[847]: verbose: CURRENT POSITION 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState stateService stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::syncState currentStatus stop Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: No code Nov 01 22:46:55 volumio volumio[847]: info: CoreStateMachine::pushState Nov 01 22:46:55 volumio volumio[847]: info: CorePlayQueue::getTrack 0 Nov 01 22:46:55 volumio volumio[847]: info: CoreCommandRouter::volumioPushState Nov 01 22:46:55 volumio volumio[847]: info: ------------------------------ 324ms Nov 01 22:46:55 volumio volumio[847]: info: ------------------------------ 171ms Nov 01 22:46:55 volumio volumio[847]: info: ------------------------------ 169ms Nov 01 22:46:56 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:47:00 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:47:04 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:47:06 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 01 22:47:07 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 22:47:08 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:47:09 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Nov 01 22:47:10 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 01 22:47:12 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 01 22:47:13 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Nov 01 22:47:13 volumio volumio[847]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Nov 01 22:47:13 volumio volumio[847]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 22:47:13 volumio volumio[847]: Error: getaddrinfo -3007 Nov 01 22:47:13 volumio volumio[847]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Nov 01 22:47:13 volumio volumio[847]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Nov 01 22:47:13 volumio volumio[847]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Nov 01 22:47:13 volumio volumio[847]: code: -3007, Nov 01 22:47:13 volumio volumio[847]: errno: -3007, Nov 01 22:47:13 volumio volumio[847]: syscall: 'getaddrinfo' Nov 01 22:47:13 volumio volumio[847]: } Nov 01 22:47:13 volumio volumio[847]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 01 22:47:13 volumio sudo[1495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-01 22:46 Nov 01 22:47:13 volumio sudo[1495]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"