-- Logs begin at Fri 2025-06-20 15:47:12 CEST, end at Fri 2025-06-20 16:16:43 CEST. --
Jun 20 16:15:03 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-SSID-REENABLED id=0 ssid="TIM-25212541-Dicios"
Jun 20 16:15:03 volumio wpa_supplicant[4651]: wlan0: SME: Trying to authenticate with 72:64:b8:91:70:5d (SSID='TIM-25212541-Dicios' freq=5180 MHz)
Jun 20 16:15:03 volumio kernel: wlan0: authenticate with 72:64:b8:91:70:5d
Jun 20 16:15:03 volumio kernel: wlan0: send auth to 72:64:b8:91:70:5d (try 1/3)
Jun 20 16:15:03 volumio wpa_supplicant[4651]: wlan0: Trying to associate with 72:64:b8:91:70:5d (SSID='TIM-25212541-Dicios' freq=5180 MHz)
Jun 20 16:15:03 volumio kernel: wlan0: authenticated
Jun 20 16:15:03 volumio kernel: wlan0: associate with 72:64:b8:91:70:5d (try 1/3)
Jun 20 16:15:03 volumio kernel: wlan0: RX AssocResp from 72:64:b8:91:70:5d (capab=0x1531 status=0 aid=16)
Jun 20 16:15:03 volumio wpa_supplicant[4651]: wlan0: Associated with 72:64:b8:91:70:5d
Jun 20 16:15:03 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 20 16:15:03 volumio kernel: wlan0: associated
Jun 20 16:15:07 volumio kernel: wlan0: deauthenticated from 72:64:b8:91:70:5d (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 20 16:15:07 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-DISCONNECTED bssid=72:64:b8:91:70:5d reason=2
Jun 20 16:15:07 volumio wpa_supplicant[4651]: wlan0: WPA: 4-Way Handshake failed - pre-shared key may be incorrect
Jun 20 16:15:07 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-SSID-TEMP-DISABLED id=0 ssid="TIM-25212541-Dicios" auth_failures=15 duration=229 reason=WRONG_KEY
Jun 20 16:15:07 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD
Jun 20 16:15:07 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=IT
Jun 20 16:15:08 volumio sudo[7442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 20 16:15:08 volumio sudo[7442]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 20 16:15:08 volumio sudo[7442]: pam_unix(sudo:session): session closed for user root
Jun 20 16:15:08 volumio sudo[7445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 20 16:15:08 volumio sudo[7445]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 20 16:15:08 volumio sudo[7445]: pam_unix(sudo:session): session closed for user root
Jun 20 16:15:08 volumio volumio[1330]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.144 UA: Mozilla/5.0 (Linux; Android 14; SM-A525F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Jun 20 16:15:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:09 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jun 20 16:15:09 volumio sudo[7448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jun 20 16:15:09 volumio sudo[7448]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 20 16:15:09 volumio sudo[7448]: pam_unix(sudo:session): session closed for user root
Jun 20 16:15:09 volumio sudo[7451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jun 20 16:15:09 volumio sudo[7451]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 20 16:15:09 volumio sudo[7451]: pam_unix(sudo:session): session closed for user root
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:09 volumio volumio[1330]: info: Prefetching next song
Jun 20 16:15:09 volumio volumio[1330]: info: DOING PREFETCH IN MPD
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand add "USB/TOSHIBA_EXT/My Music/David Bowie - Hunky Dory/17 - Bonus - How Lucky You Are.mp3"
Jun 20 16:15:09 volumio volumio[1330]: info:
Jun 20 16:15:09 volumio volumio[1330]: ---------------------------- MPD announces system playlist update
Jun 20 16:15:09 volumio volumio[1330]: info: Ignoring MPD Status Update
Jun 20 16:15:09 volumio volumio[1330]: info: sendMpdCommand add "USB/TOSHIBA_EXT/My Music/David Bowie - Hunky Dory/17 - Bonus - How Lucky You Are.mp3" took 1 milliseconds
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand consume 1
Jun 20 16:15:09 volumio volumio[1330]: info: ------------------------------ 1ms
Jun 20 16:15:09 volumio volumio[1330]: info: sendMpdCommand consume 1 took 1 milliseconds
Jun 20 16:15:09 volumio volumio[1330]: info:
Jun 20 16:15:09 volumio volumio[1330]: ---------------------------- MPD announces state update: options
Jun 20 16:15:09 volumio volumio[1330]: info: ControllerMpd::getState
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand status
Jun 20 16:15:09 volumio volumio[1330]: info: sendMpdCommand status took 1 milliseconds
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::parseState
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 20 16:15:09 volumio volumio[1330]: info: sendMpdCommand playlistinfo took 1 milliseconds
Jun 20 16:15:09 volumio volumio[1330]: verbose: ControllerMpd::parseTrackInfo
Jun 20 16:15:09 volumio volumio[1330]: info: ControllerMpd::pushState
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::servicePushState
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:09 volumio volumio[1330]: verbose: STATE SERVICE {"status":"play","position":0,"seek":219878,"duration":225,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Shadowman","artist":"David Bowie","album":null,"uri":"USB/TOSHIBA_EXT/My Music/David Bowie - Hunky Dory/16 - Bonus - The Shadowman.mp3","trackType":"mp3"}
Jun 20 16:15:09 volumio volumio[1330]: verbose: CURRENT POSITION 0
Jun 20 16:15:09 volumio volumio[1330]: info: CoreStateMachine::syncState stateService play
Jun 20 16:15:09 volumio volumio[1330]: info: CoreStateMachine::syncState currentStatus play
Jun 20 16:15:09 volumio volumio[1330]: info: Received an update from plugin. extracting info from payload
Jun 20 16:15:09 volumio volumio[1330]: info: CoreStateMachine::pushState
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState
Jun 20 16:15:09 volumio volumio[1330]: info: CoreStateMachine::pushState
Jun 20 16:15:09 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:09 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState
Jun 20 16:15:09 volumio volumio[1330]: info: ------------------------------ 17ms
Jun 20 16:15:09 volumio volumio[1330]: verbose: New Socket.io Connection to 192.168.1.151 from 192.168.1.144 UA: Mozilla/5.0 (Linux; Android 14; SM-A525F Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.115 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::volumioGetVisibleSources
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:10 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::volumioGetQueue
Jun 20 16:15:10 volumio volumio[1330]: info: CoreStateMachine::getQueue
Jun 20 16:15:10 volumio volumio[1330]: info: CorePlayQueue::getQueue
Jun 20 16:15:10 volumio volumio[1330]: info: Listing playlists
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jun 20 16:15:10 volumio volumio[1330]: info: Received Get System Info
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:10 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:10 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:10 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 20 16:15:10 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 20 16:15:11 volumio volumio[1330]: info: Received Get System Info
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:11 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:11 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Jun 20 16:15:13 volumio wpa_supplicant[4651]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=BEACON_HINT type=UNKNOWN
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jun 20 16:15:13 volumio volumio[1330]: info: Received Get System Info
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:13 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:13 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:13 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:13 volumio volumio[1330]: info: CorePlayQueue::getTrack 0
Jun 20 16:15:13 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::startPlaybackTimer
Jun 20 16:15:14 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::pushState
Jun 20 16:15:14 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState
Jun 20 16:15:14 volumio volumio[1330]: info:
Jun 20 16:15:14 volumio volumio[1330]: ---------------------------- MPD announces system playlist update
Jun 20 16:15:14 volumio volumio[1330]: info: Ignoring MPD Status Update
Jun 20 16:15:14 volumio volumio[1330]: info:
Jun 20 16:15:14 volumio volumio[1330]: ---------------------------- MPD announces state update: player
Jun 20 16:15:14 volumio volumio[1330]: info: ControllerMpd::getState
Jun 20 16:15:14 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand status
Jun 20 16:15:14 volumio volumio[1330]: info: ------------------------------ 1ms
Jun 20 16:15:14 volumio volumio[1330]: info: sendMpdCommand status took 1 milliseconds
Jun 20 16:15:14 volumio volumio[1330]: verbose: ControllerMpd::parseState
Jun 20 16:15:14 volumio volumio[1330]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Jun 20 16:15:14 volumio volumio[1330]: info: sendMpdCommand playlistinfo took 0 milliseconds
Jun 20 16:15:14 volumio volumio[1330]: verbose: ControllerMpd::parseTrackInfo
Jun 20 16:15:14 volumio volumio[1330]: info: ControllerMpd::pushState
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::servicePushState
Jun 20 16:15:14 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:14 volumio volumio[1330]: verbose: STATE SERVICE {"status":"play","position":0,"seek":68,"duration":214,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"How Lucky You Are","artist":"David Bowie","album":null,"uri":"USB/TOSHIBA_EXT/My Music/David Bowie - Hunky Dory/17 - Bonus - How Lucky You Are.mp3","trackType":"mp3"}
Jun 20 16:15:14 volumio volumio[1330]: verbose: CURRENT POSITION 1
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::syncState stateService play
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::syncState currentStatus play
Jun 20 16:15:14 volumio volumio[1330]: info: Received an update from plugin. extracting info from payload
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::pushState
Jun 20 16:15:14 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState
Jun 20 16:15:14 volumio volumio[1330]: info: CoreStateMachine::pushState
Jun 20 16:15:14 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:14 volumio volumio[1330]: info: CoreCommandRouter::volumioPushState
Jun 20 16:15:14 volumio volumio[1330]: info: ------------------------------ 12ms
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: info: Retrieving Cloud Streaming UI
Jun 20 16:15:19 volumio volumio[1330]: info: Getting Tidal Cloud Configuration
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: info: Getting Qobuz Cloud Configuration
Jun 20 16:15:19 volumio volumio[1330]: info: Asking plugin for UI Config
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: info: Getting Spotify Cloud Configuration
Jun 20 16:15:19 volumio volumio[1330]: info: Asking plugin for UI Config
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: info: Saving Spotify Acccount
Jun 20 16:15:19 volumio volumio[1330]: info: Got Tidal Cloud Configuration
Jun 20 16:15:19 volumio volumio[1330]: info: Got it
Jun 20 16:15:19 volumio volumio[1330]: info: Got it
Jun 20 16:15:19 volumio volumio[1330]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::getUIConfigOnPlugin
Jun 20 16:15:19 volumio volumio[1330]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::volumioGetBrowseSources
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jun 20 16:15:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jun 20 16:15:23 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:25 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:25 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:26 volumio volumio[1330]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L NAS-M18.local.
Jun 20 16:15:27 volumio volumio[1330]: info: Error browsing device NAS-M18 for shares: Command failed: smbclient --no-pass --debuglevel=0 -L NAS-M18.local.
Jun 20 16:15:27 volumio volumio[1330]: Unable to initialize messaging context
Jun 20 16:15:27 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jun 20 16:15:28 volumio volumio[1330]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jun 20 16:15:28 volumio volumio[1330]: info: Received Get System Version
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jun 20 16:15:28 volumio volumio[1330]: info: Received Get System Info
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:28 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:28 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:28 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:28 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:31 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:35 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:37 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jun 20 16:15:37 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jun 20 16:15:38 volumio volumio[1330]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L NAS-M18.local.
Jun 20 16:15:38 volumio volumio[1330]: info: Error browsing device NAS-M18 for shares: Command failed: smbclient --no-pass --debuglevel=0 -L NAS-M18.local.
Jun 20 16:15:38 volumio volumio[1330]: Unable to initialize messaging context
Jun 20 16:15:39 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:43 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:47 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:48 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:48 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:48 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:48 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:48 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:48 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:51 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:53 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:53 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:53 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:53 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:53 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:53 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:55 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:15:55 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:15:55 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:15:55 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:15:55 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:15:55 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:15:55 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:15:59 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:03 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:07 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:16:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:16:08 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:16:08 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:16:08 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:16:08 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:16:11 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:13 volumio volumio[1330]: info: Disabling MyMusic plugin upnp
Jun 20 16:16:13 volumio sudo[7606]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Jun 20 16:16:13 volumio sudo[7606]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jun 20 16:16:13 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD...
Jun 20 16:16:13 volumio volumio[1330]: error: Upnp client error: Error: This socket has been ended by the other party
Jun 20 16:16:15 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:19 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:20 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: networkfs , addShare
Jun 20 16:16:20 volumio volumio[1330]: info: Adding a new share
Jun 20 16:16:23 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:16:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:16:25 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:16:25 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:16:25 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:16:25 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:16:27 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:16:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:16:28 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:16:28 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:16:28 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:16:28 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:16:31 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jun 20 16:16:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jun 20 16:16:33 volumio volumio[1330]: info: Discovery: Getting this device information
Jun 20 16:16:33 volumio volumio[1330]: info: CoreCommandRouter::volumioGetState
Jun 20 16:16:33 volumio volumio[1330]: info: CorePlayQueue::getTrack 1
Jun 20 16:16:33 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jun 20 16:16:35 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:39 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jun 20 16:16:42 volumio volumio[1330]: info: Enabling MyMusic plugin upnp
Jun 20 16:16:42 volumio volumio[1330]: info: Enabling plugin upnp
Jun 20 16:16:42 volumio volumio[1330]: info: Loading plugin "upnp"...
Jun 20 16:16:42 volumio volumio[1330]: info: [1750429002928] Starting Upmpd Daemon
Jun 20 16:16:42 volumio volumio[1330]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Jun 20 16:16:42 volumio volumio[1330]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 20 16:16:42 volumio volumio[1330]: Error: listen EADDRINUSE: address already in use :::6599
Jun 20 16:16:42 volumio volumio[1330]: at Server.setupListenHandle [as _listen2] (net.js:1331:16)
Jun 20 16:16:42 volumio volumio[1330]: at listenInCluster (net.js:1379:12)
Jun 20 16:16:42 volumio volumio[1330]: at Server.listen (net.js:1465:7)
Jun 20 16:16:42 volumio volumio[1330]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Jun 20 16:16:42 volumio volumio[1330]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Jun 20 16:16:42 volumio volumio[1330]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Jun 20 16:16:42 volumio volumio[1330]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Jun 20 16:16:42 volumio volumio[1330]: at processTicksAndRejections (internal/process/task_queues.js:77:11) {
Jun 20 16:16:42 volumio volumio[1330]: code: 'EADDRINUSE',
Jun 20 16:16:42 volumio volumio[1330]: errno: -98,
Jun 20 16:16:42 volumio volumio[1330]: syscall: 'listen',
Jun 20 16:16:42 volumio volumio[1330]: address: '::',
Jun 20 16:16:42 volumio volumio[1330]: port: 6599
Jun 20 16:16:42 volumio volumio[1330]: }
Jun 20 16:16:42 volumio volumio[1330]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jun 20 16:16:43 volumio sudo[7711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-20 16:15
Jun 20 16:16:43 volumio sudo[7711]: 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="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 05:26:48 PM CEST"
VOLUMIO_VERSION="3.816"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="a63f40325536655a08b14de2b295ce76"