-- Logs begin at Thu 2019-02-14 18:11:58 CST, end at Mon 2026-02-09 15:58:18 CST. -- Feb 09 15:57:02 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 09 15:57:02 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:02 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519677 Feb 09 15:57:02 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519677 in service upnp_browser Feb 09 15:57:02 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Eagles/Hotel%20California/5ed57336-fb39-4860-abe0-25a3c617fc47.png' Feb 09 15:57:13 volumio volumio[1187]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 09 15:57:13 volumio volumio[1187]: info: Retrying Login Due to Network Error Feb 09 15:57:20 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Feb 09 15:57:20 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519952 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519953 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519954 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519955 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519956 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519957 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519958 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519959 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519960 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519961 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519962 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519963 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519964 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519965 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519966 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519946 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519947 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519948 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519949 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519950 Feb 09 15:57:20 volumio volumio[1187]: info: Preloading song: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519951 Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519952 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519953 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519954 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519955 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519956 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519957 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519958 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519959 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519960 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519961 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519962 in service upnp_browser Feb 09 15:57:20 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519963 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519964 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519965 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519966 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519946 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519947 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519948 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519949 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519950 in service upnp_browser Feb 09 15:57:21 volumio volumio[1187]: info: Exploding uri upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519951 in service upnp_browser Feb 09 15:57:22 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E8%AE%B8%E8%8C%B9%E8%8A%B8/%E5%A5%BD%E5%90%AC/52ea95f1-7d44-46e6-990a-b5feede8068e.jpg' Feb 09 15:57:23 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::stPlaybackTimer Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 4 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::serviceStop Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 4 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::serviceStop Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::stop Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::saveQueue Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Feb 09 15:57:23 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519952 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519952 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::saveQueue Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::play index 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Feb 09 15:57:23 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519953 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519953 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519954 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519954 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519955 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519955 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519956 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519956 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519957 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519957 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519958 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519958 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519959 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519959 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519960 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519960 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519961 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519961 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519962 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519962 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519963 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519963 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519964 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519964 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519965 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519965 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519966 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519966 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519946 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519946 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519947 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519947 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519948 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519948 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519949 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519949 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519950 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519950 Feb 09 15:57:23 volumio volumio[1187]: info: Adding Item to queue: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519951 Feb 09 15:57:23 volumio volumio[1187]: info: Using cached record of: upnp/http://10.0.0.3:50001/ContentDirectory/control@22$@1519951 Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::saveQueue Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::play index undefined Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: Feb 09 15:57:23 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand stop took 60 milliseconds Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:23 volumio volumio[1187]: info: Feb 09 15:57:23 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:23 volumio volumio[1187]: info: Feb 09 15:57:23 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand status took 57 milliseconds Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand status took 51 milliseconds Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand status took 50 milliseconds Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:23 volumio volumio[1187]: info: MyVolumio login type: Token Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 09 15:57:23 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 24 milliseconds Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:23 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Old cape cod.dff","artist":null,"album":null,"uri":"NAS/DS1618/HiFi Test/Tennessee Ernie Ford - Western Electric - DSD128/05 Old cape cod.dff","trackType":"dff"} Feb 09 15:57:23 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: No code Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Old cape cod.dff","artist":null,"album":null,"uri":"NAS/DS1618/HiFi Test/Tennessee Ernie Ford - Western Electric - DSD128/05 Old cape cod.dff","trackType":"dff"} Feb 09 15:57:23 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: No code Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"05 Old cape cod.dff","artist":null,"album":null,"uri":"NAS/DS1618/HiFi Test/Tennessee Ernie Ford - Western Electric - DSD128/05 Old cape cod.dff","trackType":"dff"} Feb 09 15:57:23 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState stateService stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: No code Feb 09 15:57:23 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:23 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:23 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:23 volumio volumio[1187]: info: ------------------------------ 166ms Feb 09 15:57:23 volumio volumio[1187]: info: ------------------------------ 164ms Feb 09 15:57:23 volumio volumio[1187]: info: ------------------------------ 159ms Feb 09 15:57:23 volumio volumio[1187]: info: touch_display: Setting screensaver timeout to 0 seconds. Feb 09 15:57:26 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Feb 09 15:57:26 volumio volumio[1187]: info: CoreStateMachine::play index undefined Feb 09 15:57:26 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:26 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:27 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Feb 09 15:57:27 volumio volumio[1187]: info: CoreStateMachine::play index undefined Feb 09 15:57:27 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:27 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:27 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Feb 09 15:57:27 volumio volumio[1187]: info: CoreStateMachine::play index undefined Feb 09 15:57:27 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:27 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::ClearQueue Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::stop Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::clearPlayQueue Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::saveQueue Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::addQueueItems Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::addQueueItems Feb 09 15:57:28 volumio volumio[1187]: info: Preload queue cleared Feb 09 15:57:28 volumio volumio[1187]: info: Adding Item to queue: upnp/folder/http://10.0.0.3:50001/ContentDirectory/control@22$126094 Feb 09 15:57:28 volumio volumio[1187]: info: Exploding uri upnp/folder/http://10.0.0.3:50001/ContentDirectory/control@22$126094 in service upnp_browser Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushQueue Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::saveQueue Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::updateTrackBlock Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrackBlock Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPlay Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::play index 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::stop Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::play index undefined Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::startPlaybackTimer Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: info: [1770623848380] ControllerUPNPBrowser::clearAddPlayTrack Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand stop Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand stop took 6 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand clear Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand clear took 2 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand load "http://10.0.0.3:50002/m/NDLNA/1519952.wma" Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: error: updateQueue error: null Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 4ms Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand add "http://10.0.0.3:50002/m/NDLNA/1519952.wma" Feb 09 15:57:28 volumio volumio[1187]: error: updateQueue error: null Feb 09 15:57:28 volumio volumio[1187]: error: updateQueue error: null Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 64ms Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 63ms Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand add "http://10.0.0.3:50002/m/NDLNA/1519952.wma" took 1 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand play Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces system playlist update Feb 09 15:57:28 volumio volumio[1187]: info: Ignoring MPD Status Update Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 4ms Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand play took 3 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 2ms Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 6 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 6 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: Feb 09 15:57:28 volumio volumio[1187]: ---------------------------- MPD announces state update: player Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::getState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand status Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 7 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 8 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 6 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand status took 5 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseState Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus stop Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Feb 09 15:57:28 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":810,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Feb 09 15:57:28 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 36ms Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 60ms Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 58ms Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 48 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 47 milliseconds Feb 09 15:57:28 volumio volumio[1187]: info: sendMpdCommand playlistinfo took 47 milliseconds Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:28 volumio volumio[1187]: verbose: ControllerMpd::parseTrackInfo Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":810,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Feb 09 15:57:28 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":810,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Feb 09 15:57:28 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: ControllerMpd::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::servicePushState Feb 09 15:57:28 volumio volumio[1187]: info: CorePlayQueue::getTrack 0 Feb 09 15:57:28 volumio volumio[1187]: verbose: STATE SERVICE {"status":"play","position":0,"seek":810,"duration":613,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"1519952.wma","artist":null,"album":null,"uri":"http://10.0.0.3:50002/m/NDLNA/1519952.wma","trackType":"wma"} Feb 09 15:57:28 volumio volumio[1187]: verbose: CURRENT POSITION 0 Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState stateService play Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::syncState currentStatus play Feb 09 15:57:28 volumio volumio[1187]: info: Received an update from plugin. extracting info from payload Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreStateMachine::pushState Feb 09 15:57:28 volumio volumio[1187]: info: CoreCommandRouter::volumioPushState Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 115ms Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 114ms Feb 09 15:57:28 volumio volumio[1187]: info: ------------------------------ 114ms Feb 09 15:57:32 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Nat%20King%20Cole/The%20Extraordinary%20(Deluxe%20Edition)/ed81746b-3404-460f-98e8-40763c3950cb.jpg' Feb 09 15:57:36 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Carol%20Kidd/All%20My%20Tomorrows/6c47793a-dfd7-434e-ae52-6fb1610f2c84.png' Feb 09 15:57:38 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Harry%20James/Harry%20James%20%26%20His%20Big%20Band/92d96ecf-b9c6-4c4e-9840-1f7752d85197.jpg' Feb 09 15:57:40 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Jacintha/The%20Girl%20From%20Bossa%20Nova/015da119-b495-452c-bac9-ecf1689b7fc7.jpg' Feb 09 15:57:42 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Ella%20Fitzgerald%20%26%20Louis%20Armstrong/Ella%20and%20Louis%20Again/4ec0a0ce-e66c-4437-8889-34905da33642.jpg' Feb 09 15:57:43 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Leonard%20Cohen/Ten%20New%20Songs/3453ef69-2287-42e3-ae06-ce7346fce57c.png' Feb 09 15:57:45 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Various%20Artists/Open%20Your%20Ears/80aed4db-2882-4c3f-858b-61fc1a281711.png' Feb 09 15:57:47 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Persuasions/Open%20Your%20Ears/be3146a8-81c6-43a4-800b-e5aab4eca649.jpg' Feb 09 15:57:49 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Stacey%20Kent/Songs%20From%20Other%20Places%20(Special%20Edition)/26461f34-680f-4f7d-9eba-e23e7620a4fb.jpg' Feb 09 15:57:49 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Patricia%20Barber/Verse/a585b2dc-dd8c-492c-a238-e3390c7673ef.jpg' Feb 09 15:57:49 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sara%20K./Play%20On%20Words/62fb1a5c-b29c-4544-8bf0-b0a3592bd251.jpg' Feb 09 15:57:50 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Sara%20K./No%20Cover/f4dc3645-b039-46fd-be4a-3f2e6d365071.jpg' Feb 09 15:57:53 volumio volumio[1187]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Feb 09 15:57:53 volumio volumio[1187]: info: Retrying Login Due to Network Error Feb 09 15:58:01 volumio volumio[1187]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Avi%20Avital/Between%20Worlds/0904f73b-4e9f-423d-b6c6-add1996b5813.jpg' Feb 09 15:58:03 volumio volumio[1187]: info: MyVolumio login type: Token Feb 09 15:58:17 volumio volumio[1187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:58:17 volumio volumio[1187]: Error: connect ETIMEDOUT 199.59.149.236:443 Feb 09 15:58:17 volumio volumio[1187]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Feb 09 15:58:17 volumio volumio[1187]: errno: -110, Feb 09 15:58:17 volumio volumio[1187]: code: 'ETIMEDOUT', Feb 09 15:58:17 volumio volumio[1187]: syscall: 'connect', Feb 09 15:58:17 volumio volumio[1187]: address: '199.59.149.236', Feb 09 15:58:17 volumio volumio[1187]: port: 443 Feb 09 15:58:17 volumio volumio[1187]: } Feb 09 15:58:17 volumio volumio[1187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 09 15:58:18 volumio sudo[9301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-09 15:57 Feb 09 15:58:18 volumio sudo[9301]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"