-- 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"