-- Logs begin at Mon 2024-09-02 03:35:12 CEST, end at Mon 2024-09-02 05:28:36 CEST. -- Sep 02 05:27:00 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Sep 02 05:27:00 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:00 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Sep 02 05:27:00 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:00 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:00 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:00 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:00 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:00 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:00 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:00 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:00 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:01 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:01 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 18 Sep 02 05:27:01 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:01 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:01 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:01 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:01 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:01 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:02 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 19 Sep 02 05:27:02 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:02 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:02 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:02 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:02 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:02 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:02 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:03 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 20 Sep 02 05:27:03 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:03 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:03 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:03 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:03 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 21 Sep 02 05:27:03 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:03 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:03 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:03 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:03 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:03 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:04 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 22 Sep 02 05:27:04 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:04 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:04 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:04 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:04 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:04 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:04 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:05 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 23 Sep 02 05:27:05 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:05 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:05 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:05 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:05 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Sep 02 05:27:05 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:05 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:05 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:05 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:05 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:05 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:06 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 24 Sep 02 05:27:06 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:06 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:06 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:06 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:06 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:06 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:06 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:07 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 25 Sep 02 05:27:07 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:07 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:07 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:07 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:07 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:07 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:07 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:07 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.121 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/128.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 26 Sep 02 05:27:08 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:08 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:08 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:08 volumio2 volumio[2108]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Sep 02 05:27:08 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreStateMachine::getQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CorePlayQueue::getQueue Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetVisibleSources Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 02 05:27:08 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:08 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:11 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:11 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:11 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:18 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:18 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:18 volumio2 volumio[2108]: info: Prefetching next song Sep 02 05:27:18 volumio2 volumio[2108]: info: [1725247638726] ControllerSpotify::prefetch Sep 02 05:27:18 volumio2 volumio[2108]: info: Sending Spotify command with payload to local API: /player/add_to_queue Sep 02 05:27:18 volumio2 go-librespot[4670]: time="2024-09-02T05:27:18+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 02 05:27:18 volumio2 go-librespot[4670]: time="2024-09-02T05:27:18+02:00" level=debug msg="prefetching next track" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:18 volumio2 go-librespot[4670]: time="2024-09-02T05:27:18+02:00" level=debug msg="selected format OGG_VORBIS_160 (9fa84fc3aae9efcf36d2bd4c0d44efaef94d692a)" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:18 volumio2 go-librespot[4670]: time="2024-09-02T05:27:18+02:00" level=debug msg="requested aes key for file 9fa84fc3aae9efcf36d2bd4c0d44efaef94d692a, gid: 6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=debug msg="fetched first chunk of 7, total size is 3380780 bytes" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=trace msg="seek to 0ms (diff: -500ms, samples: 0, bytes: 0)" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=info msg="prefetched track \"Ej sestro (My sister's crown CZ) - Bonus track\" (duration: 176800ms)" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=debug msg="fetched chunk 3/6, size: 524288" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=debug msg="fetched chunk 1/6, size: 524288" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:19 volumio2 go-librespot[4670]: time="2024-09-02T05:27:19+02:00" level=debug msg="fetched chunk 2/6, size: 524288" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:21 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:21 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 35 Sep 02 05:27:23 volumio2 volumio[2108]: info: CoreStateMachine::startPlaybackTimer Sep 02 05:27:23 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:23 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:23 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:23 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 05:27:23 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:23 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=trace msg="emitting websocket event: not_playing" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:62wv3N9TVz7LzdchjLPNoB","play_origin":"go-librespot"}} Sep 02 05:27:27 volumio2 volumio[2108]: error: Failed to decode event: not_playing Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"not_playing","data":{"uri":"spotify:track:62wv3N9TVz7LzdchjLPNoB","play_origin":"go-librespot"}} Sep 02 05:27:27 volumio2 volumio[2108]: error: Failed to decode event: not_playing Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=trace msg="emitting websocket event: will_play" Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","play_origin":"go-librespot"}} Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","play_origin":"go-librespot"}} Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=info msg="loaded track \"Ej sestro (My sister's crown CZ) - Bonus track\" (paused: false, position: 0ms, duration: 176800ms, prefetched: true)" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=trace msg="scheduling prefetch in 147s" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=trace msg="emitting websocket event: metadata" Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","name":"Ej sestro (My sister's crown CZ) - Bonus track","artist_names":["Vesna"],"album_name":"Ej sestro (My sister's crown CZ) [Bonus track]","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","position":0,"duration":176800,"release_date":"year:2023 month:11 day:28","track_number":1,"disc_number":1}} Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","name":"Ej sestro (My sister's crown CZ) - Bonus track","artist_names":["Vesna"],"album_name":"Ej sestro (My sister's crown CZ) [Bonus track]","album_cover_url":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","position":0,"duration":176800,"release_date":"year:2023 month:11 day:28","track_number":1,"disc_number":1}} Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 02 05:27:27 volumio2 go-librespot[4670]: time="2024-09-02T05:27:27+02:00" level=trace msg="emitting websocket event: playing" Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","play_origin":"go-librespot"}} Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: PUSH STATE SPOTIFY Sep 02 05:27:27 volumio2 volumio[2108]: SPOTIFY: {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreCommandRouter::servicePushState Sep 02 05:27:27 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:27 volumio2 volumio[2108]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:27 volumio2 volumio[2108]: verbose: CURRENT POSITION 36 Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreStateMachine::syncState stateService play Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreStateMachine::syncState currentStatus play Sep 02 05:27:27 volumio2 volumio[2108]: info: Received an update from plugin. extracting info from payload Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:27 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:27 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:27 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","play_origin":"go-librespot"}} Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: PUSH STATE SPOTIFY Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::servicePushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: verbose: CURRENT POSITION 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState stateService play Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState currentStatus play Sep 02 05:27:28 volumio2 volumio[2108]: info: Received an update from plugin. extracting info from payload Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: PUSH STATE SPOTIFY Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::servicePushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: verbose: CURRENT POSITION 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState stateService play Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState currentStatus play Sep 02 05:27:28 volumio2 volumio[2108]: info: Received an update from plugin. extracting info from payload Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: PUSH STATE SPOTIFY Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::servicePushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Ej sestro (My sister's crown CZ) - Bonus track","artist":"Vesna","album":"Ej sestro (My sister's crown CZ) [Bonus track]","albumart":"https://i.scdn.co/image/ab67616d00001e026dd38aea779e8bc19258a1fc","uri":"spotify:track:6dijO8cheTwFpxLDzO2c4b","trackType":"spotify","seek":0,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"160 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 02 05:27:28 volumio2 volumio[2108]: verbose: CURRENT POSITION 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState stateService play Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::syncState currentStatus play Sep 02 05:27:28 volumio2 volumio[2108]: info: Received an update from plugin. extracting info from payload Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreStateMachine::pushState Sep 02 05:27:28 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:28 volumio2 volumio[2108]: info: CoreCommandRouter::volumioPushState Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:28 volumio2 volumio[2108]: SPOTIFY: RECEIVED VOLUMIO VOLUME 33 Sep 02 05:27:31 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:31 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:31 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:31 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:41 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:41 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:45 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 05:27:45 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 05:27:51 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:27:51 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:27:51 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:51 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:27:52 volumio2 volumio[2108]: info: Starting Uninstall of plugin user_interface - now_playing Sep 02 05:27:52 volumio2 volumio[2108]: info: Uninstalling plugin now_playing Sep 02 05:27:52 volumio2 volumio[2108]: warn: [now-playing] MyBackgroundMonitor stopped Sep 02 05:27:52 volumio2 volumio[2108]: info: Disabling plugin now_playing Sep 02 05:27:52 volumio2 volumio[2108]: info: Checking if uninstall.sh is present Sep 02 05:27:52 volumio2 volumio[2108]: info: Executing uninstall.sh Sep 02 05:27:52 volumio2 sudo[23596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/now_playing/uninstall.sh Sep 02 05:27:52 volumio2 sudo[23596]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:27:52 volumio2 sudo[23596]: pam_unix(sudo:session): session closed for user root Sep 02 05:27:53 volumio2 volumio[2108]: info: Uninstall script completed Sep 02 05:27:53 volumio2 volumio[2108]: info: Removing plugin now_playing from configuration Sep 02 05:27:53 volumio2 volumio[2108]: info: Successfully removed now_playing configuration files Sep 02 05:27:53 volumio2 volumio[2108]: info: Plugin folders cleanup Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning into folder /volumio/app/plugins/ Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category audio_interface Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category miscellanea Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category music_service Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category plugins.json Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category system_controller Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category user_interface Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning into folder /data/plugins/ Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category music_service Sep 02 05:27:53 volumio2 volumio[2108]: info: Scanning category user_interface Sep 02 05:27:53 volumio2 volumio[2108]: info: Cleaning folder for now_playing Sep 02 05:27:54 volumio2 volumio[2108]: info: Plugin folders cleanup completed Sep 02 05:27:54 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 05:27:54 volumio2 volumio[2108]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.2.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.1.2","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"System information","name":"Systeminfo","category":"user_interface","version":"3.0.5","icon":"fa-info-circle","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 02 05:27:54 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 05:27:54 volumio2 go-librespot[4670]: time="2024-09-02T05:27:54+02:00" level=debug msg="fetched chunk 4/6, size: 524288" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:28:01 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:28:01 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:28:02 volumio2 volumio[2108]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 02 05:28:03 volumio2 volumio[2108]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined Sep 02 05:28:03 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo Sep 02 05:28:03 volumio2 sudo[23622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/user_interface/Systeminfo/firmware.sh Sep 02 05:28:03 volumio2 sudo[23622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:28:03 volumio2 sudo[23622]: pam_unix(sudo:session): session closed for user root Sep 02 05:28:09 volumio2 volumio[2108]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined Sep 02 05:28:09 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo Sep 02 05:28:09 volumio2 sudo[23908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/user_interface/Systeminfo/firmware.sh Sep 02 05:28:09 volumio2 sudo[23908]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:28:09 volumio2 sudo[23908]: pam_unix(sudo:session): session closed for user root Sep 02 05:28:16 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:16 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:16 volumio2 volumio[2108]: info: failed Error: Command failed: /bin/cat /sys/class/thermal/thermal_zone0/temp Sep 02 05:28:16 volumio2 volumio[2108]: /bin/cat: /sys/class/thermal/thermal_zone0/temp: No such file or directory Sep 02 05:28:16 volumio2 volumio[2108]: info: Discovery: Disconnected from remote: 192.168.10.58 Sep 02 05:28:16 volumio2 volumio[2108]: info: CALLMETHOD: user_interface Systeminfo getsysteminfo undefined Sep 02 05:28:16 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: Systeminfo , getsysteminfo Sep 02 05:28:17 volumio2 sudo[24180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/user_interface/Systeminfo/firmware.sh Sep 02 05:28:17 volumio2 sudo[24180]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:28:17 volumio2 sudo[24180]: pam_unix(sudo:session): session closed for user root Sep 02 05:28:23 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:23 volumio2 go-librespot[4670]: time="2024-09-02T05:28:23+02:00" level=debug msg="fetched chunk 5/6, size: 524288" uri="spotify:track:6dijO8cheTwFpxLDzO2c4b" Sep 02 05:28:24 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:24 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:24 volumio2 volumio[2108]: info: failed Error: Command failed: /bin/cat /sys/class/thermal/thermal_zone0/temp Sep 02 05:28:24 volumio2 volumio[2108]: /bin/cat: /sys/class/thermal/thermal_zone0/temp: No such file or directory Sep 02 05:28:25 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 05:28:25 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 05:28:25 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:25 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:25 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:25 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Sep 02 05:28:25 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 02 05:28:26 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:26 volumio2 volumio[2108]: Storage info 28250M 200M 26590M Sep 02 05:28:26 volumio2 volumio[2108]: info: failed Error: Command failed: /bin/cat /sys/class/thermal/thermal_zone0/temp Sep 02 05:28:26 volumio2 volumio[2108]: /bin/cat: /sys/class/thermal/thermal_zone0/temp: No such file or directory Sep 02 05:28:27 volumio2 volumio[2108]: info: CoreCommandRouter::volumioGetState Sep 02 05:28:27 volumio2 volumio[2108]: info: CorePlayQueue::getTrack 36 Sep 02 05:28:27 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:27 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:27 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:27 volumio2 volumio[2108]: info: Discovery: Connected to remote: 192.168.10.58 Sep 02 05:28:28 volumio2 volumio[2108]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> - <-AAAAAAAAAAAAA Sep 02 05:28:28 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:28 volumio2 volumio[2108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 02 05:28:28 volumio2 volumio[2108]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Sep 02 05:28:30 volumio2 volumio[2108]: info: failed Error: Command failed: /data/plugins/user_interface/Systeminfo/hw_params hw:5 >/data/configuration/user_interface/Systeminfo/config.json Sep 02 05:28:30 volumio2 volumio[2108]: cannot open device 'hw:5': Device or resource busy Sep 02 05:28:30 volumio2 volumio[2108]: info: failed Error: Command failed: /data/plugins/user_interface/Systeminfo/hw_params hw:5 >/data/configuration/user_interface/Systeminfo/config.json Sep 02 05:28:30 volumio2 volumio[2108]: cannot open device 'hw:5': Device or resource busy Sep 02 05:28:30 volumio2 volumio[2108]: info: Starting Uninstall of plugin user_interface - Systeminfo Sep 02 05:28:30 volumio2 volumio[2108]: info: Uninstalling plugin Systeminfo Sep 02 05:28:30 volumio2 volumio[2108]: info: Disabling plugin Systeminfo Sep 02 05:28:30 volumio2 volumio[2108]: info: Checking if uninstall.sh is present Sep 02 05:28:30 volumio2 volumio[2108]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> - <-AAAAAAAAAAAAA Sep 02 05:28:30 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:28:30 volumio2 volumio[2108]: info: Listing playlists Sep 02 05:28:30 volumio2 volumio[2108]: info: Executing uninstall.sh Sep 02 05:28:31 volumio2 sudo[24786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/Systeminfo/uninstall.sh Sep 02 05:28:31 volumio2 sudo[24786]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:28:31 volumio2 sudo[24801]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /etc/systemd/system/playonconnect.* Sep 02 05:28:31 volumio2 sudo[24801]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 02 05:28:31 volumio2 volumio[2108]: verbose: New Socket.io Connection to 192.168.10.43:3000 from 192.168.10.58 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Sep 02 05:28:31 volumio2 sudo[24801]: pam_unix(sudo:session): session closed for user root Sep 02 05:28:31 volumio2 sudo[24786]: pam_unix(sudo:session): session closed for user root Sep 02 05:28:31 volumio2 volumio[2108]: info: failed Error: Command failed: /data/plugins/user_interface/Systeminfo/hw_params hw:5 >/data/configuration/user_interface/Systeminfo/config.json Sep 02 05:28:31 volumio2 volumio[2108]: cannot open device 'hw:5': Device or resource busy Sep 02 05:28:31 volumio2 volumio[2108]: AAAAAAAAAAAAAAAAAAAAAAAAAA-> - <-AAAAAAAAAAAAA Sep 02 05:28:32 volumio2 volumio[2108]: Upnp client error: Error: This socket has been ended by the other party Sep 02 05:28:32 volumio2 volumio[2108]: info: Uninstall script completed Sep 02 05:28:32 volumio2 volumio[2108]: info: Removing plugin Systeminfo from configuration Sep 02 05:28:32 volumio2 volumio[2108]: info: Successfully removed Systeminfo configuration files Sep 02 05:28:32 volumio2 volumio[2108]: info: Plugin folders cleanup Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning into folder /volumio/app/plugins/ Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category audio_interface Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category miscellanea Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category music_service Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category plugins.json Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category system_controller Sep 02 05:28:32 volumio2 volumio[2108]: info: Scanning category user_interface Sep 02 05:28:33 volumio2 volumio[2108]: info: Scanning into folder /data/plugins/ Sep 02 05:28:33 volumio2 volumio[2108]: info: Scanning category music_service Sep 02 05:28:33 volumio2 volumio[2108]: info: Scanning category user_interface Sep 02 05:28:33 volumio2 volumio[2108]: info: Cleaning folder for Systeminfo Sep 02 05:28:33 volumio2 volumio[2108]: info: Plugin folders cleanup completed Sep 02 05:28:33 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 02 05:28:33 volumio2 volumio[2108]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.0","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube2","name":"youtube2","category":"music_service","version":"1.2.2","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Cast Receiver","name":"ytcr","category":"music_service","version":"1.1.2","icon":"fa-youtube","isManuallyInstalled":false,"enabled":true,"active":true}] Sep 02 05:28:33 volumio2 volumio[2108]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 02 05:28:34 volumio2 volumio[2108]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 05:28:34 volumio2 volumio[2108]: Error: ENOENT: no such file or directory, open '/data/configuration/user_interface/Systeminfo/config.json' Sep 02 05:28:34 volumio2 volumio[2108]: at Object.openSync (fs.js:498:3) Sep 02 05:28:34 volumio2 volumio[2108]: at Object.writeFileSync (fs.js:1529:35) Sep 02 05:28:34 volumio2 volumio[2108]: at Object.writeFileSync (/data/plugins/user_interface/Systeminfo/node_modules/v-conf/node_modules/jsonfile/index.js:115:13) Sep 02 05:28:34 volumio2 volumio[2108]: at Config.save (/data/plugins/user_interface/Systeminfo/node_modules/v-conf/index.js:142:12) Sep 02 05:28:34 volumio2 volumio[2108]: at Timeout._onTimeout (/data/plugins/user_interface/Systeminfo/node_modules/v-conf/index.js:129:18) Sep 02 05:28:34 volumio2 volumio[2108]: at listOnTimeout (internal/timers.js:557:17) Sep 02 05:28:34 volumio2 volumio[2108]: at processTimers (internal/timers.js:500:7) { Sep 02 05:28:34 volumio2 volumio[2108]: errno: -2, Sep 02 05:28:34 volumio2 volumio[2108]: syscall: 'open', Sep 02 05:28:34 volumio2 volumio[2108]: code: 'ENOENT', Sep 02 05:28:34 volumio2 volumio[2108]: path: '/data/configuration/user_interface/Systeminfo/config.json' Sep 02 05:28:34 volumio2 volumio[2108]: } Sep 02 05:28:34 volumio2 volumio[2108]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 05:28:36 volumio2 sudo[25021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-02 05:27 Sep 02 05:28:36 volumio2 sudo[25021]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"