-- Logs begin at Thu 2024-06-20 20:20:21 UTC, end at Fri 2024-07-19 13:26:52 UTC. --
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 19 13:25:04 volumio volumio[1026]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 19 13:25:04 volumio volumio[1026]: info: Received Get System Version
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 19 13:25:04 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:04 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:04 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:06 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:06 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.77 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:25:06 volumio volumio[1026]: info: CoreStateMachine::getQueue
Jul 19 13:25:06 volumio volumio[1026]: info: CorePlayQueue::getQueue
Jul 19 13:25:06 volumio volumio[1026]: info: Listing playlists
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 19 13:25:06 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:06 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:06 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 19 13:25:08 volumio go-librespot[1492]: time="2024-07-19T13:25:08Z" level=debug msg="fetched chunk 19/24, size: 524288"
Jul 19 13:25:20 volumio go-librespot[1492]: time="2024-07-19T13:25:20Z" level=debug msg="fetched chunk 20/24, size: 524288"
Jul 19 13:25:33 volumio go-librespot[1492]: time="2024-07-19T13:25:33Z" level=debug msg="fetched chunk 21/24, size: 524288"
Jul 19 13:25:45 volumio go-librespot[1492]: time="2024-07-19T13:25:45Z" level=debug msg="fetched chunk 22/24, size: 524288"
Jul 19 13:25:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:52 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:52 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:52 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:53 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.77 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 8
Jul 19 13:25:54 volumio sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Jul 19 13:25:54 volumio sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:25:54 volumio sudo[2223]: pam_unix(sudo:session): session closed for user root
Jul 19 13:25:54 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Jul 19 13:25:54 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0)
Jul 19 13:25:54 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root
Jul 19 13:25:54 volumio volumio[1026]: verbose: New Socket.io Connection to 192.168.1.96 from 192.168.1.77 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Total Clients: 9
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::volumioGetVisibleSources
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::volumioGetQueue
Jul 19 13:25:54 volumio volumio[1026]: info: CoreStateMachine::getQueue
Jul 19 13:25:54 volumio volumio[1026]: info: CorePlayQueue::getQueue
Jul 19 13:25:54 volumio volumio[1026]: info: Listing playlists
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Jul 19 13:25:54 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:54 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 19 13:25:54 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Jul 19 13:25:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 19 13:25:56 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:25:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:56 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:56 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:56 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:57 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus
Jul 19 13:25:57 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:25:57 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:25:57 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:25:57 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:25:57 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:25:57 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:25:57 volumio go-librespot[1492]: time="2024-07-19T13:25:57Z" level=debug msg="fetched chunk 23/24, size: 524288"
Jul 19 13:25:59 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jul 19 13:25:59 volumio volumio[1026]: info: In handleBrowseUri, curUri=spotify
Jul 19 13:25:59 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:25:59 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:25:59 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:25:59 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:01 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri
Jul 19 13:26:01 volumio volumio[1026]: info: In handleBrowseUri, curUri=spotify/playlists
Jul 19 13:26:02 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:09 volumio go-librespot[1492]: time="2024-07-19T13:26:09Z" level=debug msg="fetched chunk 24/24, size: 336919"
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Jul 19 13:26:12 volumio volumio[1026]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates
Jul 19 13:26:12 volumio volumio[1026]: info: Received Get System Version
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Jul 19 13:26:12 volumio volumio[1026]: info: Received Get System Info
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Jul 19 13:26:12 volumio volumio[1026]: info: Discovery: Getting this device information
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::volumioGetState
Jul 19 13:26:12 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Jul 19 13:26:16 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 19 13:26:16 volumio volumio[1026]: info: CURURI: music-library
Jul 19 13:26:16 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:18 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 19 13:26:18 volumio volumio[1026]: info: CURURI: music-library/INTERNAL
Jul 19 13:26:18 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:20 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 19 13:26:20 volumio volumio[1026]: info: CURURI: music-library/NAS
Jul 19 13:26:20 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:21 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Jul 19 13:26:21 volumio volumio[1026]: info: CURURI: music-library/NAS/matt
Jul 19 13:26:21 volumio volumio[1026]: error: Failed LSINFO: null
Jul 19 13:26:21 volumio volumio[1026]: info: Preload queue cleared
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:26:23 volumio volumio[1026]: info: Retrieving Cloud Streaming UI
Jul 19 13:26:23 volumio volumio[1026]: info: Getting Tidal Cloud Configuration
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:26:23 volumio volumio[1026]: info: Getting Qobuz Cloud Configuration
Jul 19 13:26:23 volumio volumio[1026]: info: Asking plugin for UI Config
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:26:23 volumio volumio[1026]: info: Getting Spotify Cloud Configuration
Jul 19 13:26:23 volumio volumio[1026]: info: Asking plugin for UI Config
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:26:23 volumio volumio[1026]: info: Saving Spotify Acccount
Jul 19 13:26:23 volumio volumio[1026]: info: Got Tidal Cloud Configuration
Jul 19 13:26:23 volumio volumio[1026]: info: Got it
Jul 19 13:26:23 volumio volumio[1026]: info: Got it
Jul 19 13:26:23 volumio volumio[1026]: error: Could not retrieve Spotify Config from plugin Spotify: no section found
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::getUIConfigOnPlugin
Jul 19 13:26:23 volumio volumio[1026]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::volumioGetBrowseSources
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:23 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares
Jul 19 13:26:24 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand update
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: info:
Jul 19 13:26:24 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand update took 13 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: info:
Jul 19 13:26:24 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:24 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: info:
Jul 19 13:26:24 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:24 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: info:
Jul 19 13:26:24 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:24 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand status took 20 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand status took 17 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand status took 16 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand status took 15 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: info: sendMpdCommand status took 14 milliseconds
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:24 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:24 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:24 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:24 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:24 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:24 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:24 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 2
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 2
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus play
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::play index undefined
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::setConsumeUpdateService undefined
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: No code
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: No code
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::startPlaybackTimer
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::clearAddPlayTracks NAS/matt/Lou Reed/Transformer (2002)/04 - Hangin’ ’Round.flac
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand stop
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 230ms
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 226ms
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 227ms
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:25 volumio volumio[1026]: info:
Jul 19 13:26:25 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:25 volumio volumio[1026]: info:
Jul 19 13:26:25 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 235 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 229 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand stop took 19 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 4 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 3 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 4 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand status took 3 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand clear
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: No code
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: No code
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:25 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:25 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: No code
Jul 19 13:26:25 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:25 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 432ms
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 202ms
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 201ms
Jul 19 13:26:25 volumio volumio[1026]: info:
Jul 19 13:26:25 volumio volumio[1026]: ---------------------------- MPD announces system playlist update
Jul 19 13:26:25 volumio volumio[1026]: info: Ignoring MPD Status Update
Jul 19 13:26:25 volumio volumio[1026]: info: sendMpdCommand clear took 194 milliseconds
Jul 19 13:26:25 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand add "NAS/matt/Lou Reed/Transformer (2002)/04 - Hangin’ ’Round.flac"
Jul 19 13:26:25 volumio volumio[1026]: info:
Jul 19 13:26:25 volumio volumio[1026]: ---------------------------- MPD announces system playlist update
Jul 19 13:26:25 volumio volumio[1026]: info: Ignoring MPD Status Update
Jul 19 13:26:25 volumio volumio[1026]: info:
Jul 19 13:26:25 volumio volumio[1026]: ---------------------------- MPD announces system playlist update
Jul 19 13:26:25 volumio volumio[1026]: info: Ignoring MPD Status Update
Jul 19 13:26:25 volumio volumio[1026]: error: updateQueue error: null
Jul 19 13:26:25 volumio volumio[1026]: error: updateQueue error: null
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 5ms
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 3ms
Jul 19 13:26:25 volumio volumio[1026]: error: Upnp client error: Error: This socket has been ended by the other party
Jul 19 13:26:25 volumio volumio[1026]: error: updateQueue error: null
Jul 19 13:26:25 volumio volumio[1026]: info: ------------------------------ 4ms
Jul 19 13:26:27 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:31 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand rescan
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand rescan took 9 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 10 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 6 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 136ms
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 132 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 130 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 3 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 2 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 394ms
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 265ms
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 265ms
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info:
Jul 19 13:26:32 volumio volumio[1026]: ---------------------------- MPD announces state update: update
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::getState
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::sendMpdCommand status
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 38 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 37 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: info: sendMpdCommand status took 37 milliseconds
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: Command Router : Notfying DB Updatefalse
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::Close All Modals sent
Jul 19 13:26:32 volumio volumio[1026]: verbose: ControllerMpd::parseState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ControllerMpd::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::servicePushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: CorePlayQueue::getTrack 3
Jul 19 13:26:32 volumio volumio[1026]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null}
Jul 19 13:26:32 volumio volumio[1026]: verbose: CURRENT POSITION 3
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState stateService stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::syncState currentStatus stop
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: No code
Jul 19 13:26:32 volumio volumio[1026]: info: CoreStateMachine::pushState
Jul 19 13:26:32 volumio volumio[1026]: info: CoreCommandRouter::volumioPushState
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 174ms
Jul 19 13:26:32 volumio volumio[1026]: info: ------------------------------ 173ms
Jul 19 13:26:35 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:39 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:43 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:45 volumio go-librespot[1492]: time="2024-07-19T13:26:45Z" level=error msg="did not receive last pong from dealer, 30s passed"
Jul 19 13:26:45 volumio go-librespot[1492]: time="2024-07-19T13:26:45Z" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusServiceRestart and reason = \"\""
Jul 19 13:26:45 volumio go-librespot[1492]: time="2024-07-19T13:26:45Z" level=debug msg="re-established dealer connection"
Jul 19 13:26:45 volumio go-librespot[1492]: time="2024-07-19T13:26:45Z" level=debug msg="received connection id: OWE0ZTY1NjgtZTY3OC00MGNiLWFmZTAtYmEwYTIyYTkxYjQxK2RlYWxlcit0Y3A6Ly9ndWMzLWRlYWxlci1hLXQ1ODMuZ3VjMy5zcG90aWZ5Lm5ldDo1NzAwK0U4NTlERjFFOUNFNkI0MTVBQkREQjZBRkYwNzZEQjQ3NkM2RDQxQUI3RkRCOUMwNUNCQ0JEOTJCREIwQzNENTU="
Jul 19 13:26:45 volumio go-librespot[1492]: time="2024-07-19T13:26:45Z" level=debug msg="put connect state because NEW_DEVICE"
Jul 19 13:26:46 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares
Jul 19 13:26:46 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService
Jul 19 13:26:47 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:51 volumio volumio[1026]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats
Jul 19 13:26:52 volumio volumio[1026]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 19 13:26:52 volumio volumio[1026]: Error: dns service error: unknown
Jul 19 13:26:52 volumio volumio[1026]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30)
Jul 19 13:26:52 volumio volumio[1026]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) {
Jul 19 13:26:52 volumio volumio[1026]: errorCode: -65537
Jul 19 13:26:52 volumio volumio[1026]: }
Jul 19 13:26:52 volumio volumio[1026]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Jul 19 13:26:52 volumio sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-19 13:25
Jul 19 13:26:52 volumio sudo[2321]: 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="9552505bba4239c163ac9ee7b0b87a56e0dd0574"
VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71"
VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1"
VOLUMIO_BE_VERSION="3d3fc502273a5c173ba19f72c1a952bdb2bdd3b0"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Tue 13 Feb 2024 07:28:53 PM CET"
VOLUMIO_VERSION="3.616"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="f11d51aa83fa91106999927181e8c0f7"