Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::servicePause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: Aug 26 19:45:24 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: player Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand pause took 0 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: Aug 26 19:45:24 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: player Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: Aug 26 19:45:24 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: player Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 0 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ------------------------------ 4ms Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:45:24 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ------------------------------ 8ms Aug 26 19:45:24 netvista-volumio4 volumio[1430]: info: ------------------------------ 8ms Aug 26 19:45:34 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:34 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Listing playlists Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreStateMachine::getQueue Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getQueue Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:41 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:42 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 19:45:46 netvista-volumio4 volumio[1430]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/139.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetVisibleSources Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Listing playlists Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetQueue Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreStateMachine::getQueue Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getQueue Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Received Get System Info Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: Discovery: Getting this device information Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:45:47 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:45:51 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 19:45:56 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 26 19:46:04 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:46:04 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:15 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetState Aug 26 19:46:15 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Retrieving Cloud Streaming UI Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Getting Tidal Cloud Configuration Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Getting Qobuz Cloud Configuration Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Asking plugin for UI Config Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Getting Spotify Cloud Configuration Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Asking plugin for UI Config Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Saving Spotify Acccount Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Got Tidal Cloud Configuration Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Got it Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: Got it Aug 26 19:46:19 netvista-volumio4 volumio[1430]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 26 19:46:19 netvista-volumio4 volumio[1430]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioGetBrowseSources Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:19 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand update took 1 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 16 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 16 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 15 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 15 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 15 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 24 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 11 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 48ms Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 48ms Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 47ms Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:21 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 12 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 12 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 11 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 22ms Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:21 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 27ms Aug 26 19:46:21 netvista-volumio4 volumio[1430]: info: ------------------------------ 27ms Aug 26 19:46:23 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand rescan Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:27 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand rescan took 2 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:27 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:27 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 2 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 2 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:27 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ------------------------------ 35ms Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ------------------------------ 34ms Aug 26 19:46:27 netvista-volumio4 volumio[1430]: info: ------------------------------ 34ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: MPD Database updated - AlbumList cache refreshed Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: database Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: MPD Database updated - AlbumList cache refreshed Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: database Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: MPD Database updated - AlbumList cache refreshed Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: database Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:31 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 12 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 11 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 12 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 27 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 27 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 27 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 39 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 39 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 39 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 20 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 50ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 51ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 50ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 50ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 10 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:31 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 67ms Aug 26 19:46:31 netvista-volumio4 volumio[1430]: info: ------------------------------ 76ms Aug 26 19:46:35 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand update took 1 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 16 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 16 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 15 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 15 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatetrue Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 41ms Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 18 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 16 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 53ms Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 52ms Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Aug 26 19:46:39 netvista-volumio4 volumio[1430]: ---------------------------- MPD announces state update: update Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::getState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand status Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 0 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 0 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 1 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 10 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 10 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand status took 11 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: Command Router : Notfying DB Updatefalse Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::Close All Modals sent Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: ControllerMpd::parseTrackInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ControllerMpd::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::servicePushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":61227,"duration":189,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1411 Kbps","isStreaming":false,"title":"Castellano (Que Bueno Baila Usted)","artist":"Phil Manzanera","album":"801 Latino","uri":"USB/344B-0908/801 Latino/01 Castellano (Que Bueno Baila Ust 1.aif","trackType":"aif"} Aug 26 19:46:39 netvista-volumio4 volumio[1430]: verbose: CURRENT POSITION 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState stateService pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::syncState currentStatus pause Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::pushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CorePlayQueue::getTrack 0 Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::volumioPushState Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: CoreStateMachine::stPlaybackTimer Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 24ms Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 25ms Aug 26 19:46:39 netvista-volumio4 volumio[1430]: info: ------------------------------ 25ms Aug 26 19:46:43 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:45 netvista-volumio4 wpa_supplicant[1215]: wlan0: CTRL-EVENT-BEACON-LOSS Aug 26 19:46:46 netvista-volumio4 wpa_supplicant[1215]: wlan0: CTRL-EVENT-BEACON-LOSS Aug 26 19:46:47 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:50 netvista-volumio4 volumio[1430]: error: error Aug 26 19:46:51 netvista-volumio4 volumio[1430]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 26 19:46:54 netvista-volumio4 volumio[1430]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:46:54 netvista-volumio4 volumio[1430]: Error: read ECONNRESET Aug 26 19:46:54 netvista-volumio4 volumio[1430]: at TLSWrap.onStreamRead (node:internal/stream_base_commons:217:20) { Aug 26 19:46:54 netvista-volumio4 volumio[1430]: errno: -104, Aug 26 19:46:54 netvista-volumio4 volumio[1430]: code: 'ECONNRESET', Aug 26 19:46:54 netvista-volumio4 volumio[1430]: syscall: 'read' Aug 26 19:46:54 netvista-volumio4 volumio[1430]: } Aug 26 19:46:54 netvista-volumio4 volumio[1430]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 26 19:46:54 netvista-volumio4 sudo[3805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-08-26 19:45' Aug 26 19:46:54 netvista-volumio4 sudo[3805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"