-- Logs begin at Mon 2024-09-02 10:44:55 UTC, end at Mon 2024-09-02 13:49:38 UTC. -- Sep 02 13:48:00 extremejukebox2 sudo[23446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Sep 02 13:48:00 extremejukebox2 sudo[23446]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:00 extremejukebox2 sudo[23446]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin bluetooth to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin multiroom to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin metavolumio to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin cd_controller to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: Starting MyVolumio Remote Streaming Endpoints Sep 02 13:48:01 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Total log size is greater than 18MB, cleaning logs Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/Xorg.0.log Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/mpd.log Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/samba/log.smbd Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/samba/log.winbindd Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/samba/log.nmbd Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/wtmp Sep 02 13:48:01 extremejukebox2 volumiologrotate[635]: Clearing /var/log/boot.log Sep 02 13:48:02 extremejukebox2 volumio[22977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:48:02 extremejukebox2 sudo[23490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Sep 02 13:48:02 extremejukebox2 sudo[23490]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:02 extremejukebox2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Sep 02 13:48:02 extremejukebox2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Sep 02 13:48:02 extremejukebox2 sudo[23490]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:04 extremejukebox2 volumio[22977]: info: camilladsp spawned new process with pid 23539, instance 1, run: true Sep 02 13:48:04 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:48:04 extremejukebox2 ntpd[778]: 185.157.229.254 local addr 192.168.1.3 -> Sep 02 13:48:05 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 13:48:10 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:48:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 02 13:48:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 02 13:48:11 extremejukebox2 volumio[22977]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: Completed starting MyVolumio Plugin Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: BOOT COMPLETED Sep 02 13:48:11 extremejukebox2 volumio[22977]: [Metrics] CommandRouter: 100s 517.64ms Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumiosetStartupVolume Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:48:11 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:48:12 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 02 13:48:13 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: camilladsp stopping service pid 23539... Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: camilladsp service terminated, instance 1 Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: camilladsp service started and running in background, instance 1 Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 02 13:48:13 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 02 13:48:13 extremejukebox2 sudo[23547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:48:13 extremejukebox2 sudo[23547]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:13 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 02 13:48:13 extremejukebox2 sudo[23547]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:14 extremejukebox2 sudo[23550]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:48:14 extremejukebox2 sudo[23550]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:14 extremejukebox2 sudo[23550]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:14 extremejukebox2 sudo[23552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:48:14 extremejukebox2 sudo[23552]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:14 extremejukebox2 sudo[23552]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:14 extremejukebox2 volumio[22977]: info: camilladsp spawned new process with pid 23570, instance 1, run: true Sep 02 13:48:21 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 02 13:48:21 extremejukebox2 volumio[22977]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 02 13:48:21 extremejukebox2 volumio[22977]: info: Retrying Login Due to Network Error Sep 02 13:48:21 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:48:21 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 13:48:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:48:22 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea8040a3.b179c617 does not match aorg 0xea8040a5.b1785547 from server@80.211.152.245 xmt 0xea8040a3.be103e8d Sep 02 13:48:23 extremejukebox2 sudo[23582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:48:23 extremejukebox2 sudo[23582]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:23 extremejukebox2 sudo[23582]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:24 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 02 13:48:24 extremejukebox2 volumio[22977]: info: camilladsp spawned new process with pid 23585, instance 1, run: true Sep 02 13:48:24 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 02 13:48:25 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Sep 02 13:48:25 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:48:26 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:48:26 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:48:27 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Sep 02 13:48:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:48:28 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:48:29 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Sep 02 13:48:30 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Sep 02 13:48:31 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Sep 02 13:48:31 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Sep 02 13:48:31 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:48:39 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Sep 02 13:48:46 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Sep 02 13:48:47 extremejukebox2 sudo[23594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:48:47 extremejukebox2 sudo[23594]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:47 extremejukebox2 sudo[23594]: pam_unix(sudo:session): session closed for user root Sep 02 13:48:48 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Sep 02 13:48:49 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:48:49 extremejukebox2 sudo[23616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:48:49 extremejukebox2 sudo[23616]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:48:49 extremejukebox2 sudo[23616]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:01 extremejukebox2 volumio[22977]: Cannot compose Albumart path Sep 02 13:49:04 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:49:04] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Sep 02 13:49:08 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: MPD Database updated - AlbumList cache refreshed Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Sep 02 13:49:08 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: database Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Sep 02 13:49:08 extremejukebox2 volumio[22977]: ---------------------------- MPD announces system playlist update Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Ignoring MPD Status Update Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Sep 02 13:49:08 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:08 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:08 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:08 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand update took 103566 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103278 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103280 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103279 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103278 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103277 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 103277 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101549 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101548 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ------------------------------ 101550ms Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101548 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101547 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101546 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ------------------------------ 101547ms Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101546 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101544 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101545 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: ------------------------------ 101546ms Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 101544 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: sendMpdCommand stop took 82707 milliseconds Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:08 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:08 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand clear Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 103942ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 103941ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 103940ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102212ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102213ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102210ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102211ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102210ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 102209ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:49:09 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:49:09] [connect] Successful connection Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Retrying Login Due to Network Error Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Sep 02 13:49:09 extremejukebox2 volumio[22977]: ---------------------------- MPD announces system playlist update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Ignoring MPD Status Update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 866 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 861 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 839 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 838 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 863ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 837 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: sendMpdCommand clear took 260 milliseconds Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand add "USB/Daphile_Music/Blue Oyster Cult/1994 - Cult Classic/01. (Don't Fear) The Reaper (Remastered).flac" Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 1080ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 1078ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 1077ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Sep 02 13:49:09 extremejukebox2 volumio[22977]: ---------------------------- MPD announces system playlist update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Ignoring MPD Status Update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Sep 02 13:49:09 extremejukebox2 volumio[22977]: ---------------------------- MPD announces system playlist update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Ignoring MPD Status Update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Sep 02 13:49:09 extremejukebox2 volumio[22977]: ---------------------------- MPD announces system playlist update Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: Ignoring MPD Status Update Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Sep 02 13:49:09 extremejukebox2 volumio-remote-updater[619]: [2024-09-02 13:49:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725284949 101 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 8 Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:09 extremejukebox2 volumio[22977]: error: updateQueue error: null Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 274ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 30ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 29ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: info: ------------------------------ 28ms Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Sep 02 13:49:09 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 Sep 02 13:49:10 extremejukebox2 sudo[23679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Sep 02 13:49:10 extremejukebox2 sudo[23679]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:10 extremejukebox2 sudo[23679]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:11 extremejukebox2 sudo[23690]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 02 13:49:11 extremejukebox2 sudo[23690]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:11 extremejukebox2 sudo[23690]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:11 extremejukebox2 sudo[23692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 02 13:49:11 extremejukebox2 sudo[23692]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:11 extremejukebox2 sudo[23692]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 192.168.1.3 from 192.168.1.5 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 17 Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 18 Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 19 Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Sep 02 13:49:11 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 21 Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Retrieving Cloud Streaming UI Sep 02 13:49:12 extremejukebox2 volumio[22977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Getting Tidal Cloud Configuration Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Getting Qobuz Cloud Configuration Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Asking plugin for UI Config Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Getting Spotify Cloud Configuration Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Asking plugin for UI Config Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Saving Spotify Acccount Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Got Tidal Cloud Configuration Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Got it Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: Got it Sep 02 13:49:12 extremejukebox2 volumio[22977]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 13:49:12 extremejukebox2 volumio[22977]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetBrowseSources Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 02 13:49:12 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 13:49:13 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:13 extremejukebox2 ntpd[778]: 93.44.243.48 local addr 192.168.1.3 -> Sep 02 13:49:14 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 02 13:49:16 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:16 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:16 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:17 extremejukebox2 volumio[22977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:49:17 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:19 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:49:21 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:22 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:23 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea8040df.b1785200 does not match aorg 0xea8040e3.b17791b4 from server@212.45.144.88 xmt 0xea8040df.b353e1b2 Sep 02 13:49:24 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 02 13:49:24 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:49:25 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:26 extremejukebox2 volumio[22977]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Sep 02 13:49:26 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 20 Sep 02 13:49:26 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:26 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:26 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Sep 02 13:49:26 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: Received Get System Version Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:28 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:29 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 02 13:49:29 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:49:29 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:29 extremejukebox2 ntpd[778]: receive: Unexpected origin timestamp 0xea8040e3.b17791b4 does not match aorg 0xea8040e5.b1779e77 from server@212.45.144.88 xmt 0xea8040e3.b881d857 Sep 02 13:49:30 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:30 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 02 13:49:31 extremejukebox2 volumio[22977]: info: MyVolumio login type: Token Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand update Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand update took 20 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 6 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 5 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 5 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 5 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ------------------------------ 702ms Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ------------------------------ 701ms Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Sep 02 13:49:32 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 715 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 715 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 17 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 17 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 17 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 16 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 7 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 6 milliseconds Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:32 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:32 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 1253ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 555ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 554ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 545ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Sep 02 13:49:33 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Sep 02 13:49:33 extremejukebox2 volumio[22977]: ---------------------------- MPD announces state update: update Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::getState Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::sendMpdCommand status Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 72 milliseconds Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 71 milliseconds Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 71 milliseconds Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: sendMpdCommand status took 71 milliseconds Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Command Router : Notfying DB Updatefalse Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::Close All Modals sent Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: ControllerMpd::parseState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ControllerMpd::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::servicePushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: 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} Sep 02 13:49:33 extremejukebox2 volumio[22977]: verbose: CURRENT POSITION 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState stateService stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::syncState currentStatus stop Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: No code Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreStateMachine::pushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioPushState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 539ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: ------------------------------ 538ms Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:33 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:33 extremejukebox2 volumio[22977]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: Received Get System Info Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: Discovery: Getting this device information Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 02 13:49:34 extremejukebox2 volumio[22977]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: MyVolumio token set successfully Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: MYVOLUMIO: Adding device Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: MYVOLUMIO: Evaluating Server Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetState Sep 02 13:49:34 extremejukebox2 volumio[22977]: info: CorePlayQueue::getTrack 0 Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: MyVolumio Plan changed: premium Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Removing browser output: myVolumio user plan is not superstar Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Removing audio output: Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: MYVOLUMIO: Adding device Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: MYVOLUMIO: Evaluating Server Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Listing playlists Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CoreCommandRouter::volumioGetQueue Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CoreStateMachine::getQueue Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: CorePlayQueue::getQueue Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Remote config written successfully Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Starting Tunnel 1 Sep 02 13:49:35 extremejukebox2 volumio[22977]: info: Starting Tunnel Connection Checker Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: MYVolumio Device enabled Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: MyVolumio status changed Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: Streaming services startup Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: Starting Streaming Daemon Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: Setting Geolocation for MyVolumio to eu6 Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 02 13:49:36 extremejukebox2 sudo[23792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 02 13:49:36 extremejukebox2 sudo[23792]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:36 extremejukebox2 sudo[23792]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: [1725284976260] Starting BluetoothController Sep 02 13:49:36 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Sep 02 13:49:37 extremejukebox2 sudo[23795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 02 13:49:37 extremejukebox2 sudo[23795]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:37 extremejukebox2 sudo[23795]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:37 extremejukebox2 volumio[22977]: /bin/mkdir: cannot create directory ‘/tmp/hls’: File exists Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: MRS: MultiRoom plugin initialized Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: MRS: STOPPING SNAPCLIENT Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: MRS: Snap server stop Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: MRS: STOPPING volumioStreaming Sep 02 13:49:37 extremejukebox2 sudo[23815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 02 13:49:37 extremejukebox2 sudo[23813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 02 13:49:37 extremejukebox2 sudo[23815]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:37 extremejukebox2 sudo[23813]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 02 13:49:37 extremejukebox2 sudo[23815]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:37 extremejukebox2 sudo[23813]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:37 extremejukebox2 sudo[23821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 02 13:49:37 extremejukebox2 sudo[23821]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:37 extremejukebox2 sudo[23817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 02 13:49:37 extremejukebox2 sudo[23817]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 13:49:37 extremejukebox2 sudo[23821]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 02 13:49:37 extremejukebox2 sudo[23817]: pam_unix(sudo:session): session closed for user root Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: Preparing to generate the ALSA configuration file Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: Updating MyVolumio device info Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Sep 02 13:49:37 extremejukebox2 volumio[22977]: info: Reading ALSA contributions from plugins. Sep 02 13:49:37 extremejukebox2 volumio[22977]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:49:37 extremejukebox2 volumio[22977]: [Error: 3069658176:error:0607A082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:611: Sep 02 13:49:37 extremejukebox2 volumio[22977]: 3069658176:error:0607A082:digital envelope routines:EVP_CIPHER_CTX_set_key_length:invalid key length:../deps/openssl/openssl/crypto/evp/evp_enc.c:611: Sep 02 13:49:37 extremejukebox2 volumio[22977]: ] { Sep 02 13:49:37 extremejukebox2 volumio[22977]: library: 'digital envelope routines', Sep 02 13:49:37 extremejukebox2 volumio[22977]: function: 'EVP_CIPHER_CTX_set_key_length', Sep 02 13:49:37 extremejukebox2 volumio[22977]: reason: 'invalid key length', Sep 02 13:49:37 extremejukebox2 volumio[22977]: code: 'ERR_SSL_INVALID_KEY_LENGTH' Sep 02 13:49:37 extremejukebox2 volumio[22977]: } Sep 02 13:49:37 extremejukebox2 volumio[22977]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 13:49:38 extremejukebox2 sudo[23833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 13:48 Sep 02 13:49:38 extremejukebox2 sudo[23833]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"