-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Fri 2024-05-10 16:08:04 CEST. -- May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:13 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:13 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.230 from 192.168.1.167 UA: unknown Total Clients: 7 May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:13 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 10 16:07:13 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.208 from 192.168.1.167 UA: unknown Total Clients: 8 May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 10 16:07:13 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 10 16:07:15 volumiohifi sudo[18354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 16:07:15 volumiohifi sudo[18354]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 16:07:15 volumiohifi sudo[18354]: pam_unix(sudo:session): session closed for user root May 10 16:07:15 volumiohifi sudo[18356]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 16:07:15 volumiohifi sudo[18356]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 16:07:15 volumiohifi sudo[18356]: pam_unix(sudo:session): session closed for user root May 10 16:07:15 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.208 from 192.168.1.167 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9 May 10 16:07:27 volumiohifi volumio[858]: info: May 10 16:07:27 volumiohifi volumio[858]: ---------------------------- MPD announces system playlist update May 10 16:07:27 volumiohifi volumio[858]: info: Ignoring MPD Status Update May 10 16:07:27 volumiohifi volumio[858]: info: May 10 16:07:27 volumiohifi volumio[858]: ---------------------------- MPD announces state update: player May 10 16:07:27 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:27 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:27 volumiohifi volumio[858]: info: ------------------------------ 3ms May 10 16:07:27 volumiohifi volumio[858]: info: sendMpdCommand status took 2 milliseconds May 10 16:07:27 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:27 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:27 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 1 milliseconds May 10 16:07:27 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:27 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:27 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:27 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:27 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1355283,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"La radio coi suoni degli anni '80 - quelli di ieri e quelli di oggi","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:27 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:27 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:27 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:27 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:27 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:27 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 16:07:27 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:27 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:27 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:27 volumiohifi volumio[858]: info: ------------------------------ 16ms May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:28 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:28 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:28 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.208 from 192.168.1.167 UA: unknown Total Clients: 8 May 10 16:07:28 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.230 from 192.168.1.167 UA: unknown Total Clients: 9 May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard May 10 16:07:28 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard May 10 16:07:29 volumiohifi sudo[18389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 10 16:07:29 volumiohifi sudo[18389]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 16:07:29 volumiohifi sudo[18389]: pam_unix(sudo:session): session closed for user root May 10 16:07:29 volumiohifi sudo[18392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 10 16:07:29 volumiohifi sudo[18392]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 16:07:29 volumiohifi sudo[18392]: pam_unix(sudo:session): session closed for user root May 10 16:07:29 volumiohifi volumio[858]: verbose: New Socket.io Connection to 192.168.1.208 from 192.168.1.167 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 10 May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetVisibleSources May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 10 16:07:29 volumiohifi volumio[858]: info: Received Get System Info May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:29 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:29 volumiohifi volumio[858]: info: Listing playlists May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 16:07:29 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache May 10 16:07:31 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 16:07:31 volumiohifi volumio[858]: info: Received Get System Info May 10 16:07:31 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:31 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:31 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:31 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:31 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 10 16:07:32 volumiohifi volumio[858]: info: Received Get System Info May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:32 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: info: Retrieving Cloud Streaming UI May 10 16:07:32 volumiohifi volumio[858]: info: Getting Tidal Cloud Configuration May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: info: Getting Qobuz Cloud Configuration May 10 16:07:32 volumiohifi volumio[858]: info: Asking plugin for UI Config May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: info: Getting Spotify Cloud Configuration May 10 16:07:32 volumiohifi volumio[858]: info: Asking plugin for UI Config May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: info: Saving Spotify Acccount May 10 16:07:32 volumiohifi volumio[858]: info: Got it May 10 16:07:32 volumiohifi volumio[858]: error: Could not retrieve Spotify Config from plugin Spotify: no section found May 10 16:07:32 volumiohifi volumio[858]: info: Got Tidal Cloud Configuration May 10 16:07:32 volumiohifi volumio[858]: info: Got it May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin May 10 16:07:32 volumiohifi volumio[858]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetBrowseSources May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetBrowseSources May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetBrowseSources May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:32 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 10 16:07:36 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:40 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:42 volumiohifi volumio[858]: info: Enabling MyMusic plugin metavolumio May 10 16:07:42 volumiohifi volumio[858]: info: [MyVolumio PluginManager] Enabling and starting plugin miscellanea metavolumio May 10 16:07:42 volumiohifi volumio[858]: info: [MyVolumio PluginManager] Plugin miscellanea metavolumio not in user plan, not enabling May 10 16:07:42 volumiohifi volumio[858]: info: Error: Plugin miscellanea metavolumio not in user plan, not enabling May 10 16:07:42 volumiohifi volumio[858]: error: Could not Enable MyMusic Plugin: Error May 10 16:07:43 volumiohifi volumio[858]: info: May 10 16:07:43 volumiohifi volumio[858]: ---------------------------- MPD announces system playlist update May 10 16:07:43 volumiohifi volumio[858]: info: Ignoring MPD Status Update May 10 16:07:43 volumiohifi volumio[858]: info: May 10 16:07:43 volumiohifi volumio[858]: ---------------------------- MPD announces state update: player May 10 16:07:43 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:43 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:43 volumiohifi volumio[858]: info: ------------------------------ 2ms May 10 16:07:43 volumiohifi volumio[858]: info: sendMpdCommand status took 1 milliseconds May 10 16:07:43 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:43 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:43 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 0 milliseconds May 10 16:07:43 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:43 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:43 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:43 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:43 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1370577,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Samantha Fox - Touch Me (I Want Your Body) (1986)","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:43 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:43 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:43 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:43 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:43 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:43 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 16:07:43 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:43 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:43 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:43 volumiohifi volumio[858]: info: ------------------------------ 13ms May 10 16:07:44 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 10 16:07:47 volumiohifi volumio[858]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 10 16:07:47 volumiohifi volumio[858]: info: Received Get System Version May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 10 16:07:47 volumiohifi volumio[858]: info: Received Get System Info May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 10 16:07:47 volumiohifi volumio[858]: info: Discovery: Getting this device information May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::volumioGetState May 10 16:07:47 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 10 16:07:48 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:52 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:52 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb May 10 16:07:52 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand update May 10 16:07:52 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:52 volumiohifi volumio[858]: info: May 10 16:07:52 volumiohifi volumio[858]: ---------------------------- MPD announces state update: update May 10 16:07:52 volumiohifi volumio[858]: info: sendMpdCommand update took 4 milliseconds May 10 16:07:52 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:52 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:52 volumiohifi volumio[858]: info: sendMpdCommand status took 2 milliseconds May 10 16:07:52 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:53 volumiohifi volumio[858]: info: Command Router : Notfying DB Updatetrue May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:53 volumiohifi volumio[858]: info: May 10 16:07:53 volumiohifi volumio[858]: ---------------------------- MPD announces state update: update May 10 16:07:53 volumiohifi volumio[858]: info: sendMpdCommand status took 89 milliseconds May 10 16:07:53 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:53 volumiohifi volumio[858]: info: sendMpdCommand status took 5 milliseconds May 10 16:07:53 volumiohifi volumio[858]: info: sendMpdCommand status took 4 milliseconds May 10 16:07:53 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 4 milliseconds May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:53 volumiohifi volumio[858]: info: Command Router : Notfying DB Updatefalse May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::Close All Modals sent May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:53 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:53 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:53 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1380385,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Samantha Fox - Touch Me (I Want Your Body) (1986)","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:53 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:53 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:53 volumiohifi volumio[858]: info: ------------------------------ 133ms May 10 16:07:53 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 13 milliseconds May 10 16:07:53 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:53 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:53 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:53 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1380488,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Samantha Fox - Touch Me (I Want Your Body) (1986)","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:53 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:53 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:53 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:53 volumiohifi volumio[858]: info: ------------------------------ 58ms May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand rescan May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:54 volumiohifi volumio[858]: info: May 10 16:07:54 volumiohifi volumio[858]: ---------------------------- MPD announces state update: update May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand rescan took 4 milliseconds May 10 16:07:54 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:54 volumiohifi volumio[858]: info: May 10 16:07:54 volumiohifi volumio[858]: ---------------------------- MPD announces state update: update May 10 16:07:54 volumiohifi volumio[858]: info: ControllerMpd::getState May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand status May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand status took 3 milliseconds May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand status took 3 milliseconds May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:54 volumiohifi volumio[858]: info: Command Router : Notfying DB Updatefalse May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::Close All Modals sent May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand status took 31 milliseconds May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand status took 30 milliseconds May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 3 milliseconds May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:54 volumiohifi volumio[858]: info: Command Router : Notfying DB Updatefalse May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::Close All Modals sent May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseState May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:54 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:54 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:54 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1382385,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Samantha Fox - Touch Me (I Want Your Body) (1986)","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:54 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:54 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:54 volumiohifi volumio[858]: info: ------------------------------ 75ms May 10 16:07:54 volumiohifi volumio[858]: info: sendMpdCommand playlistinfo took 20 milliseconds May 10 16:07:54 volumiohifi volumio[858]: verbose: ControllerMpd::parseTrackInfo May 10 16:07:54 volumiohifi volumio[858]: info: ControllerMpd::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::servicePushState May 10 16:07:54 volumiohifi volumio[858]: info: CorePlayQueue::getTrack 0 May 10 16:07:54 volumiohifi volumio[858]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1382385,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"192 Kbps","isStreaming":false,"title":"Samantha Fox - Touch Me (I Want Your Body) (1986)","artist":"Stream","album":null,"uri":"https://vps.radioduepuntozero.it/stream.mp3","trackType":"mp3"} May 10 16:07:54 volumiohifi volumio[858]: verbose: CURRENT POSITION 0 May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::syncState stateService play May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::syncState currentStatus play May 10 16:07:54 volumiohifi volumio[858]: info: Received an update from plugin. extracting info from payload May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreStateMachine::pushState May 10 16:07:54 volumiohifi volumio[858]: info: CoreCommandRouter::volumioPushState May 10 16:07:55 volumiohifi volumio[858]: info: ------------------------------ 86ms May 10 16:07:56 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:07:58 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare May 10 16:07:59 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares May 10 16:08:00 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 10 16:08:01 volumiohifi volumio[858]: info: Disabling MyMusic plugin upnp May 10 16:08:01 volumiohifi sudo[18512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service May 10 16:08:01 volumiohifi sudo[18512]: pam_unix(sudo:session): session opened for user root by (uid=0) May 10 16:08:01 volumiohifi systemd[1]: Stopping UPnP Renderer front-end to MPD... May 10 16:08:01 volumiohifi volumio[858]: error: Upnp client error: Error: This socket has been ended by the other party May 10 16:08:01 volumiohifi volumio[858]: ------------------------------------ BT MESSAGE: BT STATUS: running May 10 16:08:03 volumiohifi volumio[858]: info: Enabling MyMusic plugin upnp May 10 16:08:03 volumiohifi volumio[858]: info: Enabling plugin upnp May 10 16:08:03 volumiohifi volumio[858]: info: Loading plugin "upnp"... May 10 16:08:03 volumiohifi volumio[858]: info: [1715350083793] Starting Upmpd Daemon May 10 16:08:03 volumiohifi volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 10 16:08:03 volumiohifi volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 16:08:03 volumiohifi volumio[858]: Error: listen EADDRINUSE: address already in use :::6599 May 10 16:08:03 volumiohifi volumio[858]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) May 10 16:08:03 volumiohifi volumio[858]: at listenInCluster (net.js:1366:12) May 10 16:08:03 volumiohifi volumio[858]: at Server.listen (net.js:1452:7) May 10 16:08:03 volumiohifi volumio[858]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) May 10 16:08:03 volumiohifi volumio[858]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) May 10 16:08:03 volumiohifi volumio[858]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) May 10 16:08:03 volumiohifi volumio[858]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) May 10 16:08:03 volumiohifi volumio[858]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { May 10 16:08:03 volumiohifi volumio[858]: code: 'EADDRINUSE', May 10 16:08:03 volumiohifi volumio[858]: errno: -98, May 10 16:08:03 volumiohifi volumio[858]: syscall: 'listen', May 10 16:08:03 volumiohifi volumio[858]: address: '::', May 10 16:08:03 volumiohifi volumio[858]: port: 6599 May 10 16:08:03 volumiohifi volumio[858]: } May 10 16:08:03 volumiohifi volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 10 16:08:04 volumiohifi sudo[18530]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-10 16:07 May 10 16:08:04 volumiohifi sudo[18530]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:10:14 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5bdefd4af4423fd17aacb83a8538bf1f"