-- Logs begin at Fri 2021-10-01 10:12:00 UTC, end at Sat 2025-08-30 02:57:53 UTC. -- Aug 30 02:56:10 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:56:15 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:56:20 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:56:31 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:57:24 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::ClearQueue Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::stop Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:27 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::clearPlayQueue Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::saveQueue Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::addQueueItems Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::addQueueItems Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/folder/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$A Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/folder/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$A in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$0 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$0 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$1 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$1 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$2 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$2 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$3 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$3 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$4 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$4 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$5 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$5 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$6 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$6 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$7 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$7 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$8 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$8 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$9 Aug 30 02:57:27 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$5E$9 in service upnp_browser Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::saveQueue Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::getTrackBlock Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPlay Aug 30 02:57:27 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::play index 1 Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::stop Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:27 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::play index undefined Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::startPlaybackTimer Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:27 volumio volumio[989]: info: [1756522647511] ControllerUPNPBrowser::clearAddPlayTrack Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand stop took 277 milliseconds Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 02:57:27 volumio volumio[989]: info: Aug 30 02:57:27 volumio volumio[989]: ---------------------------- MPD announces system playlist update Aug 30 02:57:27 volumio volumio[989]: info: Ignoring MPD Status Update Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand clear took 15 milliseconds Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.105:8200/MediaItems/68913.flac" Aug 30 02:57:27 volumio volumio[989]: error: updateQueue error: null Aug 30 02:57:27 volumio volumio[989]: info: ------------------------------ 11ms Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.105:8200/MediaItems/68913.flac" Aug 30 02:57:27 volumio volumio[989]: info: Aug 30 02:57:27 volumio volumio[989]: ---------------------------- MPD announces system playlist update Aug 30 02:57:27 volumio volumio[989]: info: Ignoring MPD Status Update Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand add "http://192.168.2.105:8200/MediaItems/68913.flac" took 5 milliseconds Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand play Aug 30 02:57:27 volumio volumio[989]: info: ------------------------------ 11ms Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand play took 6 milliseconds Aug 30 02:57:27 volumio volumio[989]: info: Aug 30 02:57:27 volumio volumio[989]: ---------------------------- MPD announces state update: player Aug 30 02:57:27 volumio volumio[989]: info: ControllerMpd::getState Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Aug 30 02:57:27 volumio kernel: [SNDRV]:[atm7059_link_hw_params] ###atm7059_link_hw_params Aug 30 02:57:27 volumio kernel: atm7059_dai_hw_params,SNDRV_PCM_FORMAT_S32_LE Aug 30 02:57:27 volumio volumio[989]: info: Aug 30 02:57:27 volumio volumio[989]: ---------------------------- MPD announces state update: player Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand status took 22 milliseconds Aug 30 02:57:27 volumio volumio[989]: info: ControllerMpd::getState Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::parseState Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 02:57:27 volumio volumio[989]: info: Aug 30 02:57:27 volumio volumio[989]: ---------------------------- MPD announces system playlist update Aug 30 02:57:27 volumio volumio[989]: info: Ignoring MPD Status Update Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand status took 12 milliseconds Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand playlistinfo took 13 milliseconds Aug 30 02:57:27 volumio volumio[989]: info: ------------------------------ 11ms Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::parseState Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Aug 30 02:57:27 volumio volumio[989]: info: ControllerMpd::pushState Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::servicePushState Aug 30 02:57:27 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:27 volumio volumio[989]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"失恋演奏家","artist":"关淑怡","album":"夜迷宫","uri":"http://192.168.2.105:8200/MediaItems/68913.flac","trackType":"flac"} Aug 30 02:57:27 volumio volumio[989]: verbose: CURRENT POSITION 1 Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::syncState stateService play Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Aug 30 02:57:27 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:27 volumio volumio[989]: info: ------------------------------ 83ms Aug 30 02:57:27 volumio volumio[989]: info: sendMpdCommand playlistinfo took 39 milliseconds Aug 30 02:57:27 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Aug 30 02:57:27 volumio volumio[989]: info: ControllerMpd::pushState Aug 30 02:57:27 volumio volumio[989]: info: CoreCommandRouter::servicePushState Aug 30 02:57:28 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:28 volumio volumio[989]: verbose: STATE SERVICE {"status":"play","position":0,"seek":162,"duration":305,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"726 Kbps","isStreaming":false,"title":"失恋演奏家","artist":"关淑怡","album":"夜迷宫","uri":"http://192.168.2.105:8200/MediaItems/68913.flac","trackType":"flac"} Aug 30 02:57:28 volumio volumio[989]: verbose: CURRENT POSITION 1 Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::syncState stateService play Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus play Aug 30 02:57:28 volumio volumio[989]: info: Received an update from plugin. extracting info from payload Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:28 volumio volumio[989]: info: ------------------------------ 118ms Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:28 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:28 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:28 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:28 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:28 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:35 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::ClearQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::stop Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:38 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::stPlaybackTimer Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrackBlock Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::serviceStop Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrack 1 Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::serviceStop Aug 30 02:57:38 volumio volumio[989]: info: [1756522658555] ControllerUPNPBrowser::stop Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::clearPlayQueue Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::saveQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::addQueueItems Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::addQueueItems Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$0 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$0 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$1 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$1 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$2 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$2 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$3 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$3 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$4 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$4 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$5 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$5 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$6 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$6 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$7 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$7 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$8 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$8 in service upnp_browser Aug 30 02:57:38 volumio volumio[989]: info: Adding Item to queue: upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$9 Aug 30 02:57:38 volumio volumio[989]: info: Exploding uri upnp/http://192.168.2.105:8200/ctl/ContentDir@64$1$16$3$2D$9 in service upnp_browser Aug 30 02:57:38 volumio kernel: [SNDRV]:[atc2603c_audio_hw_free] atc2603c_audio_hw_free Aug 30 02:57:38 volumio kernel: [SNDRV]:[atc2603c_audio_hw_free] atc2603c_audio_hw_free Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::saveQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::updateTrackBlock Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrackBlock Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPlay Aug 30 02:57:38 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::play index 0 Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::stop Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:38 volumio volumio[989]: verbose: UNSET VOLATILE Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::play index undefined Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::startPlaybackTimer Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 30 02:57:38 volumio volumio[989]: info: [1756522658722] ControllerUPNPBrowser::clearAddPlayTrack Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 02:57:38 volumio volumio[989]: info: Aug 30 02:57:38 volumio volumio[989]: ---------------------------- MPD announces state update: player Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand stop took 174 milliseconds Aug 30 02:57:38 volumio volumio[989]: info: ControllerMpd::getState Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand stop took 9 milliseconds Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 02:57:38 volumio volumio[989]: info: Aug 30 02:57:38 volumio volumio[989]: ---------------------------- MPD announces system playlist update Aug 30 02:57:38 volumio volumio[989]: info: Ignoring MPD Status Update Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand status took 11 milliseconds Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand clear took 9 milliseconds Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::parseState Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.2.105:8200/MediaItems/68265.dsd" Aug 30 02:57:38 volumio volumio[989]: error: updateQueue error: null Aug 30 02:57:38 volumio volumio[989]: info: ------------------------------ 8ms Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand playlistinfo took 5 milliseconds Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Aug 30 02:57:38 volumio volumio[989]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Aug 30 02:57:38 volumio volumio[989]: info: ------------------------------ 22ms Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.2.105:8200/MediaItems/68265.dsd" Aug 30 02:57:38 volumio volumio[989]: info: Aug 30 02:57:38 volumio volumio[989]: ---------------------------- MPD announces system playlist update Aug 30 02:57:38 volumio volumio[989]: info: Ignoring MPD Status Update Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand add "http://192.168.2.105:8200/MediaItems/68265.dsd" took 3 milliseconds Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand play Aug 30 02:57:38 volumio volumio[989]: info: ------------------------------ 6ms Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand play took 3 milliseconds Aug 30 02:57:38 volumio volumio[989]: info: Aug 30 02:57:38 volumio volumio[989]: ---------------------------- MPD announces state update: player Aug 30 02:57:38 volumio volumio[989]: info: ControllerMpd::getState Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand status Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand clearerror Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand status took 7 milliseconds Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::parseState Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand clearerror took 4 milliseconds Aug 30 02:57:38 volumio volumio[989]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 02:57:38 volumio volumio[989]: verbose: ControllerMpd::parseTrackInfo Aug 30 02:57:38 volumio volumio[989]: info: ControllerMpd::pushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::servicePushState Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getTrack 0 Aug 30 02:57:38 volumio volumio[989]: 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":"68265.dsd","artist":null,"album":null,"uri":"http://192.168.2.105:8200/MediaItems/68265.dsd","trackType":"dsd"} Aug 30 02:57:38 volumio volumio[989]: verbose: CURRENT POSITION 0 Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::syncState stateService stop Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::syncState currentStatus stop Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:38 volumio volumio[989]: info: No code Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::pushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioPushState Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 02:57:38 volumio volumio[989]: info: ------------------------------ 49ms Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreCommandRouter::volumioGetQueue Aug 30 02:57:38 volumio volumio[989]: info: CoreStateMachine::getQueue Aug 30 02:57:38 volumio volumio[989]: info: CorePlayQueue::getQueue Aug 30 02:57:44 volumio volumio[989]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E9%A3%9E%E9%A3%9E/%E6%8E%8C%E5%A3%B0%E5%93%8D%E8%B5%B7/0047756b-e6b4-47d0-89a4-214f2712d5a0.jpg' Aug 30 02:57:44 volumio volumio[989]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E9%A3%9E%E9%A3%9E/%E6%8E%8C%E5%A3%B0%E5%93%8D%E8%B5%B7/0047756b-e6b4-47d0-89a4-214f2712d5a0.jpg' Aug 30 02:57:44 volumio volumio[989]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E9%A3%9E%E9%A3%9E/%E6%8E%8C%E5%A3%B0%E5%93%8D%E8%B5%B7/0047756b-e6b4-47d0-89a4-214f2712d5a0.jpg' Aug 30 02:57:44 volumio volumio[989]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E9%A3%9E%E9%A3%9E/%E6%8E%8C%E5%A3%B0%E5%93%8D%E8%B5%B7/0047756b-e6b4-47d0-89a4-214f2712d5a0.jpg' Aug 30 02:57:44 volumio volumio[989]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/%E5%87%A4%E9%A3%9E%E9%A3%9E/%E6%8E%8C%E5%A3%B0%E5%93%8D%E8%B5%B7/0047756b-e6b4-47d0-89a4-214f2712d5a0.jpg' Aug 30 02:57:52 volumio volumio[989]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 02:57:52 volumio volumio[989]: { Error: connect ETIMEDOUT 199.59.149.201:443 Aug 30 02:57:52 volumio volumio[989]: at Object._errnoException (util.js:1022:11) Aug 30 02:57:52 volumio volumio[989]: at _exceptionWithHostPort (util.js:1044:20) Aug 30 02:57:52 volumio volumio[989]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Aug 30 02:57:52 volumio volumio[989]: code: 'ETIMEDOUT', Aug 30 02:57:52 volumio volumio[989]: errno: 'ETIMEDOUT', Aug 30 02:57:52 volumio volumio[989]: syscall: 'connect', Aug 30 02:57:52 volumio volumio[989]: address: '199.59.149.201', Aug 30 02:57:52 volumio volumio[989]: port: 443 } Aug 30 02:57:52 volumio volumio[989]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 02:57:53 volumio sudo[17367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 02:56 Aug 30 02:57:53 volumio sudo[17367]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 12:12:00 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="sparky" VOLUMIO_HASH="1507c65028120e16c14994c066bc5dd4"