-- Logs begin at Thu 2019-02-14 17:11:59 +07, end at Sun 2025-08-31 06:24:42 +07. --
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:31 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:31 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:31 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.49 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:31 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 31 06:23:31 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 31 06:23:32 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:32 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:32 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:32 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:32 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:32 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:33 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:33 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:33 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:33 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:33 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:33 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:34 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:34 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50:3000 from 192.168.1.49 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7
Aug 31 06:23:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:34 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard
Aug 31 06:23:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard
Aug 31 06:23:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:34 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:35 volumio-living sudo[3721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:23:35 volumio-living sudo[3721]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:35 volumio-living sudo[3721]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:35 volumio-living sudo[3724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:23:35 volumio-living sudo[3724]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:35 volumio-living sudo[3724]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:35 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 15; BVL-N49 Build/HONORBVL-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8
Aug 31 06:23:36 volumio-living sudo[3727]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:23:36 volumio-living sudo[3727]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:36 volumio-living sudo[3727]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:36 volumio-living sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:23:36 volumio-living sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:36 volumio-living sudo[3730]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:36 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 15; BVL-N49 Build/HONORBVL-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:36 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 31 06:23:36 volumio-living volumio[1170]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Aug 31 06:23:36 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:36 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:36 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:36 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:36 volumio-living volumio[1170]: info: Listing playlists
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 31 06:23:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:23:38 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:38 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:38 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 31 06:23:40 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:40 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:40 volumio-living volumio[1170]: info: Prefetching next song
Aug 31 06:23:40 volumio-living volumio[1170]: info: DOING PREFETCH IN MPD
Aug 31 06:23:40 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand add "NAS/expansion/Music/1 SHM/SHM - Joao Gilberto - Joao Gilberto/JOAO GILBERTO (1 - Aguas De Marco).dsf"
Aug 31 06:23:40 volumio-living volumio[1170]: info: sendMpdCommand add "NAS/expansion/Music/1 SHM/SHM - Joao Gilberto - Joao Gilberto/JOAO GILBERTO (1 - Aguas De Marco).dsf" took 2 milliseconds
Aug 31 06:23:40 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand consume 1
Aug 31 06:23:40 volumio-living volumio[1170]: info:
Aug 31 06:23:40 volumio-living volumio[1170]: ---------------------------- MPD announces system playlist update
Aug 31 06:23:40 volumio-living volumio[1170]: info: Ignoring MPD Status Update
Aug 31 06:23:40 volumio-living volumio[1170]: info: sendMpdCommand consume 1 took 1 milliseconds
Aug 31 06:23:40 volumio-living volumio[1170]: info: ------------------------------ 2ms
Aug 31 06:23:40 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:23:40 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:40 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:40 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:40 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:40 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:40 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:40 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:41 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:23:41 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Aug 31 06:23:41 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Aug 31 06:23:41 volumio-living sudo[3736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Aug 31 06:23:41 volumio-living sudo[3736]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:41 volumio-living sudo[3736]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living sudo[3743]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:23:42 volumio-living sudo[3743]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living sudo[3743]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living sudo[3750]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:23:42 volumio-living sudo[3750]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living sudo[3750]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living sudo[3757]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:23:42 volumio-living sudo[3757]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living sudo[3757]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living sudo[3763]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:23:42 volumio-living sudo[3763]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living sudo[3763]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Aug 31 06:23:42 volumio-living sudo[3766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:23:42 volumio-living sudo[3766]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living sudo[3766]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:23:42 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:42 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:42 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:42 volumio-living sudo[3769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 31 06:23:42 volumio-living sudo[3769]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:23:42 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:42 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:42 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 64
Aug 31 06:23:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:23:43 volumio-living sudo[3769]: pam_unix(sudo:session): session closed for user root
Aug 31 06:23:44 volumio-living volumio[1170]: info: CoreStateMachine::startPlaybackTimer
Aug 31 06:23:44 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:45 volumio-living volumio[1170]: info: CoreStateMachine::pushState
Aug 31 06:23:45 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:45 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 31 06:23:45 volumio-living volumio[1170]: info: CoreCommandRouter::volumioPushState
Aug 31 06:23:45 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output update for this device
Aug 31 06:23:45 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output
Aug 31 06:23:45 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:45 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:45 volumio-living volumio[1170]: info:
Aug 31 06:23:45 volumio-living volumio[1170]: ---------------------------- MPD announces system playlist update
Aug 31 06:23:45 volumio-living volumio[1170]: info: Ignoring MPD Status Update
Aug 31 06:23:45 volumio-living volumio[1170]: info:
Aug 31 06:23:45 volumio-living volumio[1170]: ---------------------------- MPD announces state update: player
Aug 31 06:23:45 volumio-living volumio[1170]: info: ControllerMpd::getState
Aug 31 06:23:45 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand status
Aug 31 06:23:45 volumio-living volumio[1170]: info: ------------------------------ 2ms
Aug 31 06:23:46 volumio-living volumio[1170]: info:
Aug 31 06:23:46 volumio-living volumio[1170]: ---------------------------- MPD announces state update: player
Aug 31 06:23:46 volumio-living volumio[1170]: info: sendMpdCommand status took 1153 milliseconds
Aug 31 06:23:46 volumio-living volumio[1170]: info: ControllerMpd::getState
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand status
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::parseState
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 31 06:23:46 volumio-living volumio[1170]: info: sendMpdCommand status took 2 milliseconds
Aug 31 06:23:46 volumio-living volumio[1170]: info: sendMpdCommand playlistinfo took 3 milliseconds
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::parseState
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::parseTrackInfo
Aug 31 06:23:46 volumio-living volumio[1170]: info: ControllerMpd::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::servicePushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":330,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Aguas De Marco","artist":"JOAO GILBERTO","album":"JOAO GILBERTO","uri":"NAS/expansion/Music/1 SHM/SHM - Joao Gilberto - Joao Gilberto/JOAO GILBERTO (1 - Aguas De Marco).dsf","trackType":"dsf"}
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: CURRENT POSITION 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::syncState stateService play
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::syncState currentStatus play
Aug 31 06:23:46 volumio-living volumio[1170]: info: Received an update from plugin. extracting info from payload
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioPushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output update for this device
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioPushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output update for this device
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: ------------------------------ 1191ms
Aug 31 06:23:46 volumio-living volumio[1170]: info: sendMpdCommand playlistinfo took 30 milliseconds
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: ControllerMpd::parseTrackInfo
Aug 31 06:23:46 volumio-living volumio[1170]: info: ControllerMpd::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::servicePushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: STATE SERVICE {"status":"play","position":0,"seek":257,"duration":330,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"Aguas De Marco","artist":"JOAO GILBERTO","album":"JOAO GILBERTO","uri":"NAS/expansion/Music/1 SHM/SHM - Joao Gilberto - Joao Gilberto/JOAO GILBERTO (1 - Aguas De Marco).dsf","trackType":"dsf"}
Aug 31 06:23:46 volumio-living volumio[1170]: verbose: CURRENT POSITION 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::syncState stateService play
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::syncState currentStatus play
Aug 31 06:23:46 volumio-living volumio[1170]: info: Received an update from plugin. extracting info from payload
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioPushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output update for this device
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreStateMachine::pushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioPushState
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output update for this device
Aug 31 06:23:46 volumio-living volumio[1170]: info: MRS: Pushing multiroomSync output
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:46 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:46 volumio-living volumio[1170]: info: ------------------------------ 67ms
Aug 31 06:23:46 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 31 06:23:56 volumio-living volumio[1170]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 31 06:23:56 volumio-living volumio[1170]: info: Received Get System Version
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 06:23:56 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:23:56 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:23:56 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:23:56 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:24:03 volumio-living volumio[1170]: info: MRS: Updating multiroomSync output
Aug 31 06:24:04 volumio-living volumio[1170]: info: MRS: Updating multiroomSync output
Aug 31 06:24:07 volumio-living sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:24:07 volumio-living sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:07 volumio-living sudo[3821]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:07 volumio-living sudo[3824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:24:07 volumio-living sudo[3824]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:07 volumio-living sudo[3824]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:07 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6
Aug 31 06:24:07 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 31 06:24:07 volumio-living sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:24:07 volumio-living sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:07 volumio-living sudo[3827]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:07 volumio-living sudo[3830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:24:07 volumio-living sudo[3830]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:07 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Aug 31 06:24:07 volumio-living sudo[3830]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:07 volumio-living volumio[1170]: verbose: New Socket.io Connection to 192.168.1.50 from 192.168.1.49 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7
Aug 31 06:24:07 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetVisibleSources
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:08 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Aug 31 06:24:08 volumio-living volumio[1170]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom
Aug 31 06:24:08 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:24:08 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:08 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:08 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:08 volumio-living volumio[1170]: info: Listing playlists
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 31 06:24:08 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:24:09 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:24:09 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:09 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:24:09 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken
Aug 31 06:24:14 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Aug 31 06:24:14 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:24:14 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:24:14 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:24:14 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:24:14 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:14 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:14 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:24:17 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Aug 31 06:24:22 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:22 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Aug 31 06:24:22 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork
Aug 31 06:24:22 volumio-living sudo[3889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0
Aug 31 06:24:22 volumio-living sudo[3889]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3889]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living sudo[3896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:24:22 volumio-living sudo[3896]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3896]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:24:22 volumio-living sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3903]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living sudo[3909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0
Aug 31 06:24:22 volumio-living sudo[3909]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3909]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living sudo[3915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Aug 31 06:24:22 volumio-living sudo[3915]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3915]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache
Aug 31 06:24:22 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks
Aug 31 06:24:22 volumio-living sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Aug 31 06:24:22 volumio-living sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:22 volumio-living sudo[3918]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:22 volumio-living sudo[3921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan
Aug 31 06:24:22 volumio-living sudo[3921]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:23 volumio-living sudo[3921]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Aug 31 06:24:26 volumio-living volumio[1170]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Aug 31 06:24:26 volumio-living volumio[1170]: info: Received Get System Version
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Aug 31 06:24:26 volumio-living volumio[1170]: info: Received Get System Info
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Aug 31 06:24:26 volumio-living volumio[1170]: info: Discovery: Getting this device information
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetState
Aug 31 06:24:26 volumio-living volumio[1170]: info: CorePlayQueue::getTrack 65
Aug 31 06:24:26 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: Retrieving Cloud Streaming UI
Aug 31 06:24:34 volumio-living volumio[1170]: info: Getting Tidal Cloud Configuration
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: Getting Qobuz Cloud Configuration
Aug 31 06:24:34 volumio-living volumio[1170]: info: Asking plugin for UI Config
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: Getting Spotify Cloud Configuration
Aug 31 06:24:34 volumio-living volumio[1170]: info: Asking plugin for UI Config
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: Saving Spotify Acccount
Aug 31 06:24:34 volumio-living volumio[1170]: info: Got it
Aug 31 06:24:34 volumio-living volumio[1170]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Aug 31 06:24:34 volumio-living volumio[1170]: info: Got Tidal Cloud Configuration
Aug 31 06:24:34 volumio-living volumio[1170]: info: Got it
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::getUIConfigOnPlugin
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::volumioGetBrowseSources
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 31 06:24:34 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Aug 31 06:24:36 volumio-living volumio[1170]: info: Disabling MyMusic plugin airplay_emulation
Aug 31 06:24:36 volumio-living volumio[1170]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesShairport-Sync
Aug 31 06:24:36 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Aug 31 06:24:36 volumio-living volumio[1170]: Cannot find translation for source QOBUZ
Aug 31 06:24:36 volumio-living volumio[1170]: Cannot find translation for source TIDAL
Aug 31 06:24:36 volumio-living volumio[1170]: info: Disabling plugin airplay_emulation
Aug 31 06:24:36 volumio-living volumio[1170]: info: Done.
Aug 31 06:24:36 volumio-living sudo[3963]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop shairport-sync
Aug 31 06:24:36 volumio-living sudo[3963]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:36 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Aug 31 06:24:36 volumio-living systemd[1]: shairport-sync.service: Succeeded.
Aug 31 06:24:36 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Aug 31 06:24:36 volumio-living volumio[1170]: ------------------------------------ BT MESSAGE: BT STATUS: running
Aug 31 06:24:36 volumio-living sudo[3963]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:36 volumio-living volumio[1170]: info: Shairport-Sync Stopped
Aug 31 06:24:38 volumio-living volumio[1170]: info: Enabling MyMusic plugin airplay_emulation
Aug 31 06:24:38 volumio-living volumio[1170]: info: Enabling plugin airplay_emulation
Aug 31 06:24:38 volumio-living volumio[1170]: info: Loading plugin "airplay_emulation"...
Aug 31 06:24:38 volumio-living volumio[1170]: info: Starting Shairport Sync
Aug 31 06:24:38 volumio-living volumio[1170]: info: PLUGIN START: airplay_emulation
Aug 31 06:24:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 06:24:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 06:24:38 volumio-living volumio[1170]: xcb_connection_has_error() returned true
Aug 31 06:24:38 volumio-living volumio[1170]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 06:24:38 volumio-living volumio[1170]: xcb_connection_has_error() returned true
Aug 31 06:24:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Aug 31 06:24:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Aug 31 06:24:38 volumio-living volumio[1170]: xcb_connection_has_error() returned true
Aug 31 06:24:38 volumio-living volumio[1170]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0
Aug 31 06:24:38 volumio-living volumio[1170]: xcb_connection_has_error() returned true
Aug 31 06:24:38 volumio-living volumio[1170]: info: Done.
Aug 31 06:24:38 volumio-living volumio[1170]: info: Starting Shairport Sync
Aug 31 06:24:38 volumio-living volumio[1170]: info: Starting Shairport Sync
Aug 31 06:24:38 volumio-living sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 06:24:38 volumio-living sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:38 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Aug 31 06:24:38 volumio-living sudo[3975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync
Aug 31 06:24:38 volumio-living sudo[3975]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:38 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Aug 31 06:24:38 volumio-living sudo[3972]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:38 volumio-living volumio[1170]: info: Shairport-Sync Started
Aug 31 06:24:38 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver...
Aug 31 06:24:38 volumio-living volumio[1170]: ------------------------------------ BT MESSAGE: BT STATUS: running
Aug 31 06:24:38 volumio-living systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM
Aug 31 06:24:38 volumio-living systemd[1]: shairport-sync.service: Succeeded.
Aug 31 06:24:38 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver.
Aug 31 06:24:38 volumio-living volumio[1170]: ------------------------------------ BT MESSAGE: BT STATUS: waiting
Aug 31 06:24:38 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver.
Aug 31 06:24:38 volumio-living sudo[3975]: pam_unix(sudo:session): session closed for user root
Aug 31 06:24:38 volumio-living volumio[1170]: info: Shairport-Sync Started
Aug 31 06:24:40 volumio-living volumio[1170]: info: Disabling MyMusic plugin upnp
Aug 31 06:24:40 volumio-living sudo[3982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service
Aug 31 06:24:40 volumio-living sudo[3982]: pam_unix(sudo:session): session opened for user root by (uid=0)
Aug 31 06:24:40 volumio-living systemd[1]: Stopping UPnP Renderer front-end to MPD...
Aug 31 06:24:40 volumio-living volumio[1170]: error: Upnp client error: Error: This socket has been ended by the other party
Aug 31 06:24:40 volumio-living volumio[1170]: ------------------------------------ BT MESSAGE: BT STATUS: running
Aug 31 06:24:42 volumio-living volumio[1170]: info: Enabling MyMusic plugin upnp
Aug 31 06:24:42 volumio-living volumio[1170]: info: Enabling plugin upnp
Aug 31 06:24:42 volumio-living volumio[1170]: info: Loading plugin "upnp"...
Aug 31 06:24:42 volumio-living volumio[1170]: info: [1756596282066] Starting Upmpd Daemon
Aug 31 06:24:42 volumio-living volumio[1170]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Aug 31 06:24:42 volumio-living volumio[1170]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 06:24:42 volumio-living volumio[1170]: Error: listen EADDRINUSE: address already in use :::6599
Aug 31 06:24:42 volumio-living volumio[1170]: at Server.setupListenHandle [as _listen2] (net.js:1318:16)
Aug 31 06:24:42 volumio-living volumio[1170]: at listenInCluster (net.js:1366:12)
Aug 31 06:24:42 volumio-living volumio[1170]: at Server.listen (net.js:1452:7)
Aug 31 06:24:42 volumio-living volumio[1170]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17)
Aug 31 06:24:42 volumio-living volumio[1170]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38)
Aug 31 06:24:42 volumio-living volumio[1170]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19)
Aug 31 06:24:42 volumio-living volumio[1170]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28)
Aug 31 06:24:42 volumio-living volumio[1170]: at processTicksAndRejections (internal/process/task_queues.js:75:11) {
Aug 31 06:24:42 volumio-living volumio[1170]: code: 'EADDRINUSE',
Aug 31 06:24:42 volumio-living volumio[1170]: errno: -98,
Aug 31 06:24:42 volumio-living volumio[1170]: syscall: 'listen',
Aug 31 06:24:42 volumio-living volumio[1170]: address: '::',
Aug 31 06:24:42 volumio-living volumio[1170]: port: 6599
Aug 31 06:24:42 volumio-living volumio[1170]: }
Aug 31 06:24:42 volumio-living volumio[1170]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Aug 31 06:24:42 volumio-living sudo[3993]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-31 06:23
Aug 31 06:24:42 volumio-living sudo[3993]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010"
VOLUMIO_ARCH="arm"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST"
VOLUMIO_VERSION="3.832"
VOLUMIO_HARDWARE="pi"
VOLUMIO_DEVICENAME="Raspberry Pi"
VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"