-- Logs begin at Sat 2024-10-26 12:53:15 UTC, end at Sat 2024-10-26 14:01:48 UTC. -- Oct 26 14:00:03 volumio sudo[865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/SX100 /mnt/NAS/MINI_SERVER_SX100 Oct 26 14:00:03 volumio sudo[865]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:03 volumio kernel: CIFS: Attempting to mount //192.168.1.104/SX100 Oct 26 14:00:03 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Oct 26 14:00:03 volumio kernel: CIFS: VFS: \\192.168.1.104 Send error in SessSetup = -13 Oct 26 14:00:03 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Oct 26 14:00:03 volumio sudo[865]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:03 volumio sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Guest,password=ft4ac6RU3U,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/Users /mnt/NAS/MINI_SERVER Oct 26 14:00:03 volumio sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:04 volumio kernel: CIFS: Attempting to mount //192.168.1.104/Users Oct 26 14:00:04 volumio kernel: CIFS: Status code returned 0xc000006d STATUS_LOGON_FAILURE Oct 26 14:00:04 volumio kernel: CIFS: VFS: \\192.168.1.104 Send error in SessSetup = -13 Oct 26 14:00:04 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Oct 26 14:00:04 volumio sudo[867]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:04 volumio sudo[871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/Users\SX100\Music /mnt/NAS/MINI_SERVER_MUSIC Oct 26 14:00:04 volumio sudo[871]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:04 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:05 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:05 volumio sudo[877]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/S-Drive /mnt/NAS/MINI_SERVER Oct 26 14:00:05 volumio kernel: CIFS: Attempting to mount //192.168.1.104/Users\SX100\Music Oct 26 14:00:05 volumio sudo[877]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:05 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Oct 26 14:00:05 volumio kernel: CIFS: VFS: \\192.168.1.104 Send error in SessSetup = -13 Oct 26 14:00:05 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Oct 26 14:00:05 volumio sudo[871]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:05 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:05 volumio volumio[582]: error: MPD error: Error: write EPIPE Oct 26 14:00:05 volumio kernel: CIFS: Attempting to mount //192.168.1.104/S-Drive Oct 26 14:00:05 volumio kernel: CIFS: Status code returned 0xc0000022 STATUS_ACCESS_DENIED Oct 26 14:00:05 volumio kernel: CIFS: VFS: \\192.168.1.104 Send error in SessSetup = -13 Oct 26 14:00:05 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -13 Oct 26 14:00:05 volumio sudo[877]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:06 volumio volumio[582]: info: Cannot mount NAS MINI_SERVER_SX100 at system boot, trial number 4 ,retrying in 5 seconds Oct 26 14:00:06 volumio volumio[582]: info: MPD Permissions set Oct 26 14:00:06 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:06 volumio volumio[582]: info: Cannot mount NAS MINI_SERVER at system boot, trial number 4 ,retrying in 5 seconds Oct 26 14:00:06 volumio volumio[582]: info: Cannot mount NAS MINI_SERVER_MUSIC at system boot, trial number 4 ,retrying in 5 seconds Oct 26 14:00:06 volumio volumio[582]: info: Cannot mount NAS MINI_SERVER at system boot, trial number 4 ,retrying in 5 seconds Oct 26 14:00:06 volumio volumio[582]: info: Volumio called home Oct 26 14:00:07 volumio volumio[582]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 22 Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:00:08 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:00:09 volumio volumio[582]: info: Starting Shairport Sync Oct 26 14:00:09 volumio volumio[582]: info: Starting Shairport Sync Oct 26 14:00:09 volumio volumio[582]: info: Starting Shairport Sync Oct 26 14:00:10 volumio sudo[894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 14:00:10 volumio sudo[894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:10 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 14:00:10 volumio sudo[896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 14:00:10 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 26 14:00:10 volumio sudo[896]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:10 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:11 volumio sudo[894]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:11 volumio sudo[899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 26 14:00:11 volumio sudo[899]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 14:00:11 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 26 14:00:11 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 26 14:00:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:12 volumio sudo[896]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:12 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 26 14:00:12 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Oct 26 14:00:12 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 26 14:00:12 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:12 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 26 14:00:13 volumio sudo[899]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:13 volumio sudo[905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/SX100 /mnt/NAS/MINI_SERVER_SX100 Oct 26 14:00:13 volumio sudo[905]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:13 volumio kernel: CIFS: Attempting to mount //192.168.1.104/SX100 Oct 26 14:00:14 volumio volumio[582]: error: MPD error: The expression evaluated to a falsy value: Oct 26 14:00:14 volumio volumio[582]: assert.ok(self.idling) Oct 26 14:00:15 volumio volumio[582]: info: Shairport-Sync Started Oct 26 14:00:15 volumio volumio[582]: Error adding Membership: Error: addMembership EINVAL Oct 26 14:00:15 volumio volumio[582]: info: Shairport-Sync Started Oct 26 14:00:16 volumio sudo[913]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/S-Drive /mnt/NAS/MINI_SERVER Oct 26 14:00:16 volumio sudo[913]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:16 volumio kernel: CIFS: Attempting to mount //192.168.1.104/S-Drive Oct 26 14:00:16 volumio sudo[915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/Users\SX100\Music /mnt/NAS/MINI_SERVER_MUSIC Oct 26 14:00:16 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:17 volumio volumio[582]: error: MPD error: The expression evaluated to a falsy value: Oct 26 14:00:17 volumio volumio[582]: assert.ok(self.idling) Oct 26 14:00:17 volumio sudo[915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:17 volumio volumio[582]: info: Shairport-Sync Started Oct 26 14:00:17 volumio kernel: CIFS: Attempting to mount //192.168.1.104/Users\SX100\Music Oct 26 14:00:17 volumio sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Guest,password=ft4ac6RU3U,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.104/Users /mnt/NAS/MINI_SERVER Oct 26 14:00:17 volumio sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:17 volumio volumio[582]: info: CoreCommandRouter::volumioGetState Oct 26 14:00:17 volumio kernel: CIFS: Attempting to mount //192.168.1.104/Users Oct 26 14:00:17 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:18 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:18 volumio volumio[582]: error: MPD error: The expression evaluated to a falsy value: Oct 26 14:00:18 volumio volumio[582]: assert.ok(self.idling) Oct 26 14:00:20 volumio mpd[860]: Oct 26 14:00 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 26 14:00:20 volumio mpd[860]: Oct 26 14:00 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Oct 26 14:00:20 volumio mpd[860]: Oct 26 14:00 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Oct 26 14:00:20 volumio systemd[1]: Started Music Player Daemon. Oct 26 14:00:20 volumio volumio[582]: error: MPD error: The expression evaluated to a falsy value: Oct 26 14:00:20 volumio volumio[582]: assert.ok(self.idling) Oct 26 14:00:20 volumio sudo[845]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:20 volumio volumio[582]: error: updateQueue error: null Oct 26 14:00:20 volumio volumio[582]: error: updateQueue error: null Oct 26 14:00:21 volumio volumio[582]: [yt-cast-receiver] Listening on port 8098 Oct 26 14:00:21 volumio volumio[582]: info: Completed starting Core Plugins Oct 26 14:00:21 volumio volumio[582]: info: ------------------------------------------- Oct 26 14:00:21 volumio volumio[582]: info: ----- MyVolumio plugins startup ---- Oct 26 14:00:21 volumio volumio[582]: info: ------------------------------------------- Oct 26 14:00:21 volumio volumio[582]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 26 14:00:22 volumio volumio[582]: error: updateQueue error: null Oct 26 14:00:22 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:23 volumio volumio[582]: error: updateQueue error: null Oct 26 14:00:23 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 26 14:00:23 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 26 14:00:23 volumio sudo[905]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:23 volumio volumio[582]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 26 14:00:25 volumio volumio[582]: error: updateQueue error: null Oct 26 14:00:26 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:26 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 26 14:00:26 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 26 14:00:26 volumio sudo[913]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:27 volumio volumio[582]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 26 14:00:27 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 26 14:00:27 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 26 14:00:27 volumio sudo[915]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:27 volumio volumio[582]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 26 14:00:28 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Oct 26 14:00:28 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Oct 26 14:00:28 volumio sudo[919]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:28 volumio volumio[582]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Oct 26 14:00:28 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 26 14:00:28 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:00:30 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin bluetooth to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin multiroom to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin metavolumio to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin cd_controller to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin smart_inputs to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: Adding plugin tidalconnect to MyMusic Plugins Oct 26 14:00:30 volumio volumio[582]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Oct 26 14:00:43 volumio volumio[582]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 26 14:00:43 volumio volumio[582]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 26 14:00:43 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:00:43 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:00:43 volumio volumio[582]: info: Starting MyVolumio Remote Streaming Endpoints Oct 26 14:00:44 volumio volumio[582]: info: MyVolumio login type: Token Oct 26 14:00:44 volumio volumio[582]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 26 14:00:44 volumio volumio[582]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 26 14:00:46 volumio volumio[582]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 26 14:00:46 volumio volumio[582]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 26 14:00:46 volumio volumio[582]: info: Streaming services startup Oct 26 14:00:46 volumio volumio[582]: info: Starting Streaming Daemon Oct 26 14:00:47 volumio sudo[933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 26 14:00:47 volumio sudo[933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:00:47 volumio systemd[1]: Stopping Volumio Streaming Daemon... Oct 26 14:00:47 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM Oct 26 14:00:47 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. Oct 26 14:00:47 volumio systemd[1]: Stopped Volumio Streaming Daemon. Oct 26 14:00:47 volumio systemd[1]: Started Volumio Streaming Daemon. Oct 26 14:00:47 volumio sudo[933]: pam_unix(sudo:session): session closed for user root Oct 26 14:00:47 volumio volumio-streaming-daemon[939]: ############################ Oct 26 14:00:47 volumio volumio-streaming-daemon[939]: # Volumio Streaming Daemon # Oct 26 14:00:47 volumio volumio-streaming-daemon[939]: # Running on port 7777 # Oct 26 14:00:47 volumio volumio-streaming-daemon[939]: ############################ Oct 26 14:00:47 volumio volumio-streaming-daemon[939]: Environment: production Oct 26 14:00:48 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:48 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:48 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 26 14:00:48 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand rescan Oct 26 14:00:48 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:00:48 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 26 14:00:48 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand update Oct 26 14:00:50 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:50 volumio volumio[582]: info: Oct 26 14:00:50 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:50 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:50 volumio volumio[582]: info: Oct 26 14:00:50 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:50 volumio volumio[582]: info: sendMpdCommand rescan took 2000 milliseconds Oct 26 14:00:50 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:50 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:50 volumio volumio[582]: info: sendMpdCommand update took 2113 milliseconds Oct 26 14:00:50 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:50 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: info: Oct 26 14:00:51 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:51 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: info: Oct 26 14:00:51 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:51 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:51 volumio volumio[582]: info: Oct 26 14:00:51 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:51 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:51 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:52 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:52 volumio volumio[582]: info: Oct 26 14:00:52 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:52 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:52 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:52 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:52 volumio volumio[582]: info: Oct 26 14:00:52 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:52 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:52 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:53 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:53 volumio volumio[582]: info: Oct 26 14:00:53 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:53 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:53 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 3118 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 2930 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 2695 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 2550 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 2144 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 2051 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1907 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1802 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1761 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1637 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1569 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1446 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1241 milliseconds Oct 26 14:00:53 volumio volumio[582]: info: sendMpdCommand status took 1131 milliseconds Oct 26 14:00:53 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:54 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:54 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:55 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:55 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:55 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:55 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:55 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:55 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:56 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:56 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:56 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:56 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:56 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:57 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:00:57 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:57 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:57 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:57 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:57 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:58 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:58 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:58 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:00:58 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:00:58 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:00:58 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:58 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:00:58 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:00:58 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 8168ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 7981ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 7182ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 6962ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 6788ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 6585ms Oct 26 14:00:58 volumio volumio[582]: info: ------------------------------ 6263ms Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: info: Oct 26 14:00:58 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:58 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: info: Oct 26 14:00:58 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:58 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: info: Oct 26 14:00:58 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:58 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:58 volumio volumio[582]: info: Oct 26 14:00:58 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:58 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:58 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:59 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:59 volumio volumio[582]: info: Oct 26 14:00:59 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:00:59 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:00:59 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:00:59 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 26 14:00:59 volumio volumio[582]: info: CURURI: music-library Oct 26 14:00:59 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 6955 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 6627 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1407 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1347 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1346 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1320 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1319 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1295 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1282 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1250 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1096 milliseconds Oct 26 14:01:00 volumio volumio[582]: info: sendMpdCommand status took 1050 milliseconds Oct 26 14:01:00 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:00 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:00 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:00 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:00 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:00 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:00 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:00 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:00 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:01 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:01 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:01 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:01 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:01 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:01 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:02 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:02 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:02 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 9736ms Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 4180ms Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 4113ms Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 4082ms Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 4042ms Oct 26 14:01:02 volumio volumio[582]: info: ------------------------------ 3864ms Oct 26 14:01:02 volumio volumio[582]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 26 14:01:03 volumio volumio[582]: info: Retrying Login Due to Network Error Oct 26 14:01:03 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:03 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:03 volumio volumio[582]: info: Retrieving Cloud Streaming UI Oct 26 14:01:03 volumio volumio-streaming-daemon[939]: Environment: production Oct 26 14:01:04 volumio volumio[582]: info: Getting Tidal Cloud Configuration Oct 26 14:01:04 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:04 volumio volumio[582]: info: Getting Qobuz Cloud Configuration Oct 26 14:01:04 volumio volumio[582]: info: Asking plugin for UI Config Oct 26 14:01:04 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:04 volumio volumio[582]: info: Getting Spotify Cloud Configuration Oct 26 14:01:04 volumio volumio[582]: info: Asking plugin for UI Config Oct 26 14:01:04 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:04 volumio volumio[582]: info: Saving Spotify Acccount Oct 26 14:01:04 volumio volumio[582]: info: Got Tidal Cloud Configuration Oct 26 14:01:04 volumio volumio[582]: info: Got it Oct 26 14:01:04 volumio volumio[582]: info: Got it Oct 26 14:01:04 volumio volumio[582]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 14:01:04 volumio volumio[582]: info: Fetching Streaming Services browse cache Oct 26 14:01:04 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:04 volumio volumio[582]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 14:01:07 volumio volumio[582]: error: MyVolumio Plugin failed to authenticate in a timely fashion Oct 26 14:01:07 volumio volumio[582]: info: Completed starting MyVolumio Plugin Oct 26 14:01:07 volumio volumio[582]: info: BOOT COMPLETED Oct 26 14:01:07 volumio volumio[582]: [Metrics] CommandRouter: 187s 631.61ms Oct 26 14:01:07 volumio volumio[582]: info: CoreCommandRouter::volumiosetStartupVolume Oct 26 14:01:07 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 26 14:01:07 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:07 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:07 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:09 volumio volumio[582]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 14:01:11 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:11 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:11 volumio volumio[582]: info: Retrieving Cloud Streaming UI Oct 26 14:01:11 volumio volumio[582]: info: Getting Tidal Cloud Configuration Oct 26 14:01:11 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:11 volumio volumio[582]: info: Getting Qobuz Cloud Configuration Oct 26 14:01:11 volumio volumio[582]: info: Asking plugin for UI Config Oct 26 14:01:11 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:12 volumio volumio[582]: info: Getting Spotify Cloud Configuration Oct 26 14:01:12 volumio volumio[582]: info: Asking plugin for UI Config Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:12 volumio volumio[582]: info: Saving Spotify Acccount Oct 26 14:01:12 volumio volumio[582]: info: Got Tidal Cloud Configuration Oct 26 14:01:12 volumio volumio[582]: info: Got it Oct 26 14:01:12 volumio volumio[582]: info: Got it Oct 26 14:01:12 volumio volumio[582]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 26 14:01:12 volumio volumio[582]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 26 14:01:12 volumio volumio[582]: info: CoreCommandRouter::volumioGetBrowseSources Oct 26 14:01:13 volumio volumio[582]: info: MyVolumio login type: Token Oct 26 14:01:14 volumio volumio[582]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 26 14:01:14 volumio volumio[582]: info: MYVOLUMIO: Adding device Oct 26 14:01:14 volumio volumio[582]: info: MYVOLUMIO: Evaluating Server Oct 26 14:01:15 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:16 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:21 volumio volumio[582]: info: MyVolumio status changed Oct 26 14:01:21 volumio volumio[582]: info: Streaming services startup Oct 26 14:01:21 volumio volumio[582]: info: Re-Starting Streaming Daemon Oct 26 14:01:21 volumio volumio[582]: info: Removing browser output: myVolumio user plan is not superstar Oct 26 14:01:21 volumio volumio[582]: info: Removing audio output: Oct 26 14:01:21 volumio volumio[582]: info: Stoppping Tunnel 1 Oct 26 14:01:22 volumio sudo[1010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Oct 26 14:01:22 volumio sudo[1010]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 26 14:01:22 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:22 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Oct 26 14:01:22 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand rescan Oct 26 14:01:22 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Oct 26 14:01:22 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand update Oct 26 14:01:22 volumio sudo[1010]: pam_unix(sudo:session): session closed for user root Oct 26 14:01:22 volumio volumio-streaming-daemon[939]: Restarting daemon Oct 26 14:01:22 volumio volumio-streaming-daemon[939]: Environment: production Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand rescan took 485 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand update took 484 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Setting Geolocation for MyVolumio to eu6 Oct 26 14:01:23 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:23 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:23 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:23 volumio volumio[582]: info: Remote SSH Stopped Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: Oct 26 14:01:23 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:23 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 618 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 618 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 592 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 582 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 460 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 446 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 433 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 408 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 404 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 397 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 384 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 374 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 351 milliseconds Oct 26 14:01:23 volumio volumio[582]: info: sendMpdCommand status took 329 milliseconds Oct 26 14:01:23 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:24 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:24 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:24 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:24 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:24 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:24 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:25 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:25 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:25 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:25 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:25 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:25 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:25 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:25 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:25 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:25 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:25 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:25 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:25 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:25 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:26 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:26 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:26 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:26 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:26 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:26 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:26 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3708ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3717ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3537ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3506ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3480ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3452ms Oct 26 14:01:26 volumio volumio[582]: info: ------------------------------ 3416ms Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: info: Oct 26 14:01:26 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: info: Oct 26 14:01:26 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: info: Oct 26 14:01:26 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: info: Oct 26 14:01:26 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:26 volumio volumio[582]: info: Oct 26 14:01:26 volumio volumio[582]: ---------------------------- MPD announces state update: update Oct 26 14:01:26 volumio volumio[582]: info: ControllerMpd::getState Oct 26 14:01:26 volumio volumio[582]: verbose: ControllerMpd::sendMpdCommand status Oct 26 14:01:27 volumio volumio[582]: info: Updating MyVolumio device info Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 3519 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 3483 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 331 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 311 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 298 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 276 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 264 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 242 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 253 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 229 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 209 milliseconds Oct 26 14:01:27 volumio volumio[582]: info: sendMpdCommand status took 198 milliseconds Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:27 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:27 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:28 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:28 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:28 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:28 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:28 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:28 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:28 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:28 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:28 volumio volumio[582]: info: Command Router : Notfying DB Updatefalse Oct 26 14:01:28 volumio volumio[582]: info: CoreCommandRouter::Close All Modals sent Oct 26 14:01:28 volumio volumio[582]: verbose: ControllerMpd::parseState Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ControllerMpd::pushState Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::servicePushState Oct 26 14:01:29 volumio volumio[582]: info: CoreStateMachine::pushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::volumioPushState Oct 26 14:01:29 volumio volumio[582]: info: CorePlayQueue::getTrack 0 Oct 26 14:01:29 volumio volumio[582]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 6290ms Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 3096ms Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 3064ms Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 3030ms Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 3011ms Oct 26 14:01:29 volumio volumio[582]: info: ------------------------------ 2964ms Oct 26 14:01:29 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:30 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:30 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:30 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:30 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:31 volumio volumio-streaming-daemon[939]: Environment: production Oct 26 14:01:31 volumio volumio[582]: info: Fetching Streaming Services browse cache Oct 26 14:01:31 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:32 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:33 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:34 volumio sudo[636]: pam_unix(sudo:session): session closed for user root Oct 26 14:01:34 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:35 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:35 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:36 volumio volumio[582]: info: Cannot mount NAS MINI_SERVER2 at system boot, trial number 1 ,retrying in 5 seconds Oct 26 14:01:36 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:37 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:37 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:39 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Oct 26 14:01:39 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 26 14:01:40 volumio volumio[582]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 26 14:01:41 volumio volumio[582]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:01:41 volumio volumio[582]: TypeError: Cannot read property 'replace' of undefined Oct 26 14:01:41 volumio volumio[582]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Oct 26 14:01:41 volumio volumio[582]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Oct 26 14:01:41 volumio volumio[582]: at listOnTimeout (internal/timers.js:554:17) Oct 26 14:01:41 volumio volumio[582]: at processTimers (internal/timers.js:497:7) Oct 26 14:01:41 volumio volumio[582]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 26 14:01:48 volumio sudo[1096]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-26 14:00 Oct 26 14:01:48 volumio sudo[1096]: 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="1c5fa5a8788b087ca429be9d10851b25c5cc4f5b" VOLUMIO_FE_VERSION="e5ce74e254a237ac98580c4437c4ed223c7d5cd9" VOLUMIO_FE3_VERSION="a86adf3c7ed2d6c50d9bca768f58ab2f56832dc1" VOLUMIO_BE_VERSION="c8882d89d3d4e0a1571e0dc9701741128f5a1656" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 31 Mar 2022 02:51:41 PM CEST" VOLUMIO_VERSION="3.251" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e83cc5fcd68eb3ee4105ab0b8b3779ea"