-- Logs begin at Sat 2024-11-02 05:31:09 UTC, end at Sat 2024-11-02 05:35:35 UTC. -- Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 05:34:10 volumio volumio[1228]: info: Discovery: Getting this device information Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 05:34:10 volumio volumio[1228]: info: Discovery: Getting this device information Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:34:10 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 05:34:10 volumio volumio[1228]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.33 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 02 05:34:11 volumio volumio[1228]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Nov 02 05:34:11 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:34:11 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 05:34:11 volumio volumio[1228]: info: Listing playlists Nov 02 05:34:11 volumio volumio[1228]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 05:34:11 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:34:11 volumio volumio[1228]: info: CoreCommandRouter::volumioGetQueue Nov 02 05:34:11 volumio volumio[1228]: info: CoreStateMachine::getQueue Nov 02 05:34:11 volumio volumio[1228]: info: CorePlayQueue::getQueue Nov 02 05:34:13 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::ClearQueue Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::stPlaybackTimer Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::serviceStop Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::serviceStop Nov 02 05:34:13 volumio volumio[1228]: info: [1730525653810] ControllerWebradio::stop Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::clearPlayQueue Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::addQueueItems Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::addQueueItems Nov 02 05:34:13 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:13 volumio volumio[1228]: info: Adding Item to queue: http://91.232.125.197:8888/stream Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPlay Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::play index 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::play index undefined Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::startPlaybackTimer Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: [1730525653841] ControllerWebradio::clearAddPlayTrack Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand stop took 36 milliseconds Nov 02 05:34:13 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand stop took 6 milliseconds Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:13 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:13 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:13 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand status took 11 milliseconds Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand clear took 7 milliseconds Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand load "http://91.232.125.197:8888/stream" Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:13 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:13 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:13 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:13 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:13 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:13 volumio volumio[1228]: info: ------------------------------ 35ms Nov 02 05:34:13 volumio volumio[1228]: info: ------------------------------ 34ms Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:13 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:13 volumio volumio[1228]: info: Nov 02 05:34:13 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:13 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:13 volumio volumio[1228]: error: updateQueue error: null Nov 02 05:34:13 volumio volumio[1228]: info: ------------------------------ 37ms Nov 02 05:34:13 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 34 milliseconds Nov 02 05:34:13 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:13 volumio volumio[1228]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 05:34:13 volumio volumio[1228]: info: ------------------------------ 51ms Nov 02 05:34:14 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand add "http://91.232.125.197:8888/stream" Nov 02 05:34:14 volumio volumio[1228]: error: updateQueue error: null Nov 02 05:34:14 volumio volumio[1228]: error: updateQueue error: null Nov 02 05:34:14 volumio volumio[1228]: info: ------------------------------ 634ms Nov 02 05:34:14 volumio volumio[1228]: info: ------------------------------ 633ms Nov 02 05:34:14 volumio volumio[1228]: info: Nov 02 05:34:14 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:14 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:14 volumio volumio[1228]: info: sendMpdCommand add "http://91.232.125.197:8888/stream" took 2 milliseconds Nov 02 05:34:14 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 05:34:14 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand play Nov 02 05:34:14 volumio volumio[1228]: info: Nov 02 05:34:14 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:14 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:14 volumio volumio[1228]: info: Nov 02 05:34:14 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:14 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:14 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:14 volumio volumio[1228]: info: sendMpdCommand play took 4 milliseconds Nov 02 05:34:14 volumio volumio[1228]: info: ------------------------------ 3ms Nov 02 05:34:14 volumio volumio[1228]: info: ------------------------------ 3ms Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 8 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 7 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 38ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 66ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 65ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 57 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 55 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 54 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 110ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 105ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 53 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 134ms Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 14ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 12 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 13ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 9 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 8ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:16 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:16 volumio volumio[1228]: info: Nov 02 05:34:16 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 19ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 16 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 19ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 16 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 13 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 12ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 9 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 9ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":428,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":610,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":735,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 117ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 116ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 111ms Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 88 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 88 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 85 milliseconds Nov 02 05:34:16 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 84 milliseconds Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":862,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:16 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:16 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":987,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:16 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:16 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:16 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 178ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 178ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 170ms Nov 02 05:34:16 volumio volumio[1228]: info: ------------------------------ 167ms Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 7ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 4ms Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 9 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 8ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 3ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 55ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 54ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 40 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 40 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 40 milliseconds Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1237,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 116ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 114ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 111ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 110ms Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:17 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:17 volumio volumio[1228]: info: Nov 02 05:34:17 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 10ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 8 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 6ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 05:34:17 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1859,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1859,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:17 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:17 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1859,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:17 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:17 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:17 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 89ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 85ms Nov 02 05:34:17 volumio volumio[1228]: info: ------------------------------ 83ms Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 35ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 32 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 30ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 27 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 25ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 17 milliseconds Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2360,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 85ms Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 80ms Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 70ms Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:18 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:18 volumio volumio[1228]: info: Nov 02 05:34:18 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 8ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 8 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 7ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 77ms Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 77ms Nov 02 05:34:18 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 66 milliseconds Nov 02 05:34:18 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:18 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:18 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:18 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2982,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:18 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:18 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:18 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:18 volumio volumio[1228]: info: ------------------------------ 96ms Nov 02 05:34:19 volumio volumio[1228]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 02 05:34:19 volumio volumio[1228]: info: Retrying Login Due to Network Error Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:20 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:20 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:20 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:20 volumio volumio[1228]: info: Nov 02 05:34:20 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 8ms Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand status took 8 milliseconds Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 7ms Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 6ms Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 6 milliseconds Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:20 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:20 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:20 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:20 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:20 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:20 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:20 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:20 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 86ms Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 84ms Nov 02 05:34:20 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 74 milliseconds Nov 02 05:34:20 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:20 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:20 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:20 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":4107,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Hudbu20032258","artist":"hudbu.com // music, simply.","album":null,"uri":"http://91.232.125.197:8888/stream","trackType":""} Nov 02 05:34:20 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:20 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:20 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:20 volumio volumio[1228]: info: ------------------------------ 103ms Nov 02 05:34:27 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::ClearQueue Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::stPlaybackTimer Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::serviceStop Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::serviceStop Nov 02 05:34:27 volumio volumio[1228]: info: [1730525667651] ControllerWebradio::stop Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::clearPlayQueue Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::addQueueItems Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::addQueueItems Nov 02 05:34:27 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:27 volumio volumio[1228]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687 Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPlay Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::play index 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::play index undefined Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::startPlaybackTimer Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: [1730525667687] ControllerWebradio::clearAddPlayTrack Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand stop took 40 milliseconds Nov 02 05:34:27 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand stop took 5 milliseconds Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:27 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:27 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:27 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand clear took 5 milliseconds Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:27 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:27 volumio volumio[1228]: info: ------------------------------ 20ms Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:27 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:27 volumio volumio[1228]: info: Nov 02 05:34:27 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:27 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:27 volumio volumio[1228]: error: updateQueue error: null Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand status took 22 milliseconds Nov 02 05:34:27 volumio volumio[1228]: info: ------------------------------ 22ms Nov 02 05:34:27 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 20 milliseconds Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:27 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:27 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:27 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:27 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:27 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:27 volumio volumio[1228]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 05:34:27 volumio volumio[1228]: info: ------------------------------ 40ms Nov 02 05:34:27 volumio volumio[1228]: info: ------------------------------ 45ms Nov 02 05:34:28 volumio volumio[1228]: info: Nov 02 05:34:28 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:28 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:28 volumio volumio[1228]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99555687" took 1148 milliseconds Nov 02 05:34:28 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 05:34:28 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand play Nov 02 05:34:28 volumio volumio[1228]: info: ------------------------------ 1135ms Nov 02 05:34:28 volumio volumio[1228]: info: ------------------------------ 1134ms Nov 02 05:34:28 volumio volumio[1228]: info: Nov 02 05:34:28 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:28 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:28 volumio volumio[1228]: info: Nov 02 05:34:28 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:28 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:28 volumio volumio[1228]: info: ------------------------------ 9ms Nov 02 05:34:28 volumio volumio[1228]: info: sendMpdCommand play took 8 milliseconds Nov 02 05:34:28 volumio volumio[1228]: info: ------------------------------ 6ms Nov 02 05:34:28 volumio volumio[1228]: info: ------------------------------ 4ms Nov 02 05:34:29 volumio volumio[1228]: info: MyVolumio login type: Token Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: Nov 02 05:34:29 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 13 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 10 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 9 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 8 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:29 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 44ms Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 75ms Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand status took 63 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 58 milliseconds Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 57 milliseconds Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:29 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:29 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:29 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 148ms Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 146ms Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 141ms Nov 02 05:34:29 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 81 milliseconds Nov 02 05:34:29 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:29 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"citypopfour","artist":"(#1 - 1/512) City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:29 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:29 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:29 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:29 volumio volumio[1228]: info: ------------------------------ 169ms Nov 02 05:34:29 volumio volumio[1228]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F512)%20City%20Pop%20Four/0173112e-21a6-4da3-a6b2-1d09f54b7a0e.jpg' Nov 02 05:34:29 volumio volumio[1228]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F512)%20City%20Pop%20Four/0173112e-21a6-4da3-a6b2-1d09f54b7a0e.jpg' Nov 02 05:34:29 volumio volumio[1228]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/(%231%20-%201%2F512)%20City%20Pop%20Four/0173112e-21a6-4da3-a6b2-1d09f54b7a0e.jpg' Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:30 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:30 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:30 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:30 volumio volumio[1228]: info: Nov 02 05:34:30 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 9ms Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand status took 8 milliseconds Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 9ms Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 7ms Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 05:34:30 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:30 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:30 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:30 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":603,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Li Yaming · Philosophy Of Waiting (1983)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:30 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:30 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:30 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:30 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Li Yaming · Philosophy Of Waiting (1983)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:30 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:30 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:30 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:30 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Li Yaming · Philosophy Of Waiting (1983)","artist":"City Pop Four","album":null,"uri":"http://65.21.61.215:8000/citypopfour","trackType":""} Nov 02 05:34:30 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:30 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:30 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 103ms Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 101ms Nov 02 05:34:30 volumio volumio[1228]: info: ------------------------------ 99ms Nov 02 05:34:33 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::ClearQueue Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::stPlaybackTimer Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::serviceStop Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::serviceStop Nov 02 05:34:33 volumio volumio[1228]: info: [1730525673400] ControllerWebradio::stop Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::clearPlayQueue Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::addQueueItems Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::addQueueItems Nov 02 05:34:33 volumio volumio[1228]: info: Preload queue cleared Nov 02 05:34:33 volumio volumio[1228]: info: Adding Item to queue: http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8 Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPushQueue Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::saveQueue Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::updateTrackBlock Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrackBlock Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPlay Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::play index 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::stop Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::play index undefined Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::startPlaybackTimer Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: [1730525673421] ControllerWebradio::clearAddPlayTrack Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand stop took 24 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand stop took 5 milliseconds Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:33 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:33 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand status took 6 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand clear took 5 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8" Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:33 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:33 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:33 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:33 volumio volumio[1228]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 34ms Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 34ms Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: error: updateQueue error: null Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 37ms Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 31 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand add "http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8" took 31 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 4ms Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:33 volumio volumio[1228]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 05:34:33 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand play Nov 02 05:34:33 volumio volumio[1228]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 46ms Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: info: Nov 02 05:34:33 volumio volumio[1228]: ---------------------------- MPD announces system playlist update Nov 02 05:34:33 volumio volumio[1228]: info: Ignoring MPD Status Update Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 11ms Nov 02 05:34:33 volumio volumio[1228]: info: sendMpdCommand play took 8 milliseconds Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 6ms Nov 02 05:34:33 volumio volumio[1228]: info: ------------------------------ 5ms Nov 02 05:34:39 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 05:34:39 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 05:34:39 volumio volumio[1228]: info: Discovery: Getting this device information Nov 02 05:34:39 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:34:39 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 5 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 4 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: Nov 02 05:34:44 volumio volumio[1228]: ---------------------------- MPD announces state update: player Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::getState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand status Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 7 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 5 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 4 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand status took 3 milliseconds Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseState Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus stop Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:44 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:44 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: ------------------------------ 34ms Nov 02 05:34:44 volumio volumio[1228]: info: ------------------------------ 52ms Nov 02 05:34:44 volumio volumio[1228]: info: sendMpdCommand playlistinfo took 43 milliseconds Nov 02 05:34:44 volumio volumio[1228]: verbose: ControllerMpd::parseTrackInfo Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:44 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:44 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: ControllerMpd::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::servicePushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"bbc_6music-audio=320000.m3u8","artist":null,"album":null,"uri":"http://as-hls-ww-live.akamaized.net/pool_904/live/ww/bbc_6music/bbc_6music.isml/bbc_6music-audio=320000.m3u8","trackType":"m3u8"} Nov 02 05:34:44 volumio volumio[1228]: verbose: CURRENT POSITION 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState stateService play Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::syncState currentStatus play Nov 02 05:34:44 volumio volumio[1228]: info: Received an update from plugin. extracting info from payload Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CorePlayQueue::getTrack 0 Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreStateMachine::pushState Nov 02 05:34:44 volumio volumio[1228]: info: CoreCommandRouter::volumioPushState Nov 02 05:34:44 volumio volumio[1228]: info: ------------------------------ 114ms Nov 02 05:34:44 volumio volumio[1228]: info: ------------------------------ 109ms Nov 02 05:34:44 volumio volumio[1228]: info: ------------------------------ 108ms Nov 02 05:34:59 volumio volumio[1228]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Nov 02 05:34:59 volumio volumio[1228]: info: Retrying Login Due to Network Error Nov 02 05:35:09 volumio volumio[1228]: info: MyVolumio login type: Token Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 05:35:12 volumio volumio[1228]: info: Discovery: Getting this device information Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 05:35:12 volumio volumio[1228]: info: Discovery: Getting this device information Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:35:12 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 05:35:14 volumio volumio[1228]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.33 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Nov 02 05:35:15 volumio volumio[1228]: verbose: New Socket.io Connection to 192.168.1.35 from 192.168.1.33 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Nov 02 05:35:15 volumio volumio[1228]: info: CoreCommandRouter::volumioGetState Nov 02 05:35:15 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 05:35:15 volumio volumio[1228]: info: Listing playlists Nov 02 05:35:15 volumio volumio[1228]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 05:35:15 volumio volumio[1228]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 05:35:15 volumio volumio[1228]: info: CoreCommandRouter::volumioGetQueue Nov 02 05:35:15 volumio volumio[1228]: info: CoreStateMachine::getQueue Nov 02 05:35:15 volumio volumio[1228]: info: CorePlayQueue::getQueue Nov 02 05:35:35 volumio volumio[1228]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:35:35 volumio volumio[1228]: Error: connect ETIMEDOUT 162.125.82.7:80 Nov 02 05:35:35 volumio volumio[1228]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Nov 02 05:35:35 volumio volumio[1228]: errno: -110, Nov 02 05:35:35 volumio volumio[1228]: code: 'ETIMEDOUT', Nov 02 05:35:35 volumio volumio[1228]: syscall: 'connect', Nov 02 05:35:35 volumio volumio[1228]: address: '162.125.82.7', Nov 02 05:35:35 volumio volumio[1228]: port: 80 Nov 02 05:35:35 volumio volumio[1228]: } Nov 02 05:35:35 volumio volumio[1228]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 05:35:35 volumio sudo[2833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-02 05:34 Nov 02 05:35:35 volumio sudo[2833]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"