-- Logs begin at Fri 2013-04-12 00:01:06 UTC, end at Wed 2026-10-14 02:20:56 UTC. -- Oct 14 02:19:00 volumio volumio[3421]: info: CorePlayQueue::saveQueue Oct 14 02:19:00 volumio volumio[3421]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:19:00 volumio volumio[3421]: info: CoreStateMachine::updateTrackBlock Oct 14 02:19:00 volumio volumio[3421]: info: CorePlayQueue::getTrackBlock Oct 14 02:19:09 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::addQueueItems Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::addQueueItems Oct 14 02:19:22 volumio volumio[3421]: info: Adding Item to queue: upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@64$1$31 Oct 14 02:19:22 volumio volumio[3421]: info: Exploding uri upnp/folder/http://192.168.31.149:8200/ctl/ContentDir@64$1$31 in service upnp_browser Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::saveQueue Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::updateTrackBlock Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::getTrackBlock Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioPlay Oct 14 02:19:22 volumio volumio[3421]: verbose: UNSET VOLATILE Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::play index 1012 Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::stop Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:22 volumio volumio[3421]: verbose: UNSET VOLATILE Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::play index undefined Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:22 volumio volumio[3421]: info: [1791944362418] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand stop took 35 milliseconds Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 14 02:19:22 volumio volumio[3421]: info: Oct 14 02:19:22 volumio volumio[3421]: ---------------------------- MPD announces system playlist update Oct 14 02:19:22 volumio volumio[3421]: info: Ignoring MPD Status Update Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand clear took 3 milliseconds Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/1579.wav" Oct 14 02:19:22 volumio volumio[3421]: error: updateQueue error: null Oct 14 02:19:22 volumio volumio[3421]: info: ------------------------------ 59ms Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/1579.wav" Oct 14 02:19:22 volumio volumio[3421]: info: Oct 14 02:19:22 volumio volumio[3421]: ---------------------------- MPD announces system playlist update Oct 14 02:19:22 volumio volumio[3421]: info: Ignoring MPD Status Update Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/1579.wav" took 0 milliseconds Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:19:22 volumio volumio[3421]: info: ------------------------------ 2ms Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:19:22 volumio volumio[3421]: info: Oct 14 02:19:22 volumio volumio[3421]: ---------------------------- MPD announces state update: player Oct 14 02:19:22 volumio volumio[3421]: info: ControllerMpd::getState Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:19:22 volumio volumio[3421]: info: Oct 14 02:19:22 volumio volumio[3421]: ---------------------------- MPD announces state update: player Oct 14 02:19:22 volumio volumio[3421]: info: ControllerMpd::getState Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand status took 1 milliseconds Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::parseState Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand status took 2 milliseconds Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::parseState Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:19:22 volumio volumio[3421]: info: ControllerMpd::pushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::servicePushState Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:22 volumio volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1579.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/1579.wav","trackType":"wav"} Oct 14 02:19:22 volumio volumio[3421]: verbose: CURRENT POSITION 1012 Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::syncState stateService play Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:22 volumio volumio[3421]: info: ------------------------------ 10ms Oct 14 02:19:22 volumio volumio[3421]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 02:19:22 volumio volumio[3421]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:19:22 volumio volumio[3421]: info: ControllerMpd::pushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::servicePushState Oct 14 02:19:22 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:22 volumio volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":251,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1579.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/1579.wav","trackType":"wav"} Oct 14 02:19:22 volumio volumio[3421]: verbose: CURRENT POSITION 1012 Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::syncState stateService play Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:19:22 volumio volumio[3421]: info: Received an update from plugin. extracting info from payload Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:22 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:22 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:22 volumio volumio[3421]: info: ------------------------------ 13ms Oct 14 02:19:53 volumio volumio[3421]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 02:19:53 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 02:19:53 volumio volumio[3421]: info: CoreCommandRouter::volumioGetState Oct 14 02:19:53 volumio volumio[3421]: info: CoreCommandRouter::volumioGetQueue Oct 14 02:19:53 volumio volumio[3421]: info: CoreStateMachine::getQueue Oct 14 02:19:53 volumio volumio[3421]: info: CorePlayQueue::getQueue Oct 14 02:19:53 volumio volumio[3421]: info: Listing playlists Oct 14 02:19:53 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioAddQueueItems Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::addQueueItems Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::addQueueItems Oct 14 02:19:55 volumio volumio[3421]: info: Adding Item to queue: upnp/http://192.168.31.149:8200/ctl/ContentDir@64$1$31$3 Oct 14 02:19:55 volumio volumio[3421]: info: Exploding uri upnp/http://192.168.31.149:8200/ctl/ContentDir@64$1$31$3 in service upnp_browser Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::saveQueue Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushQueue Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::updateTrackBlock Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrackBlock Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPlay Oct 14 02:19:55 volumio volumio[3421]: verbose: UNSET VOLATILE Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::play index 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::stop Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:55 volumio volumio[3421]: verbose: UNSET VOLATILE Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::stPlaybackTimer Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::updateTrackBlock Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrackBlock Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::serviceStop Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1012 Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::serviceStop Oct 14 02:19:55 volumio volumio[3421]: info: [1791944395564] ControllerUPNPBrowser::stop Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:19:55 volumio volumio[3421]: info: Oct 14 02:19:55 volumio volumio[3421]: ---------------------------- MPD announces state update: player Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand stop took 106 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::getState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::play index undefined Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::startPlaybackTimer Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: info: [1791944395671] ControllerUPNPBrowser::clearAddPlayTrack Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand status took 21 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand stop took 21 milliseconds Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 02:19:55 volumio volumio[3421]: info: Oct 14 02:19:55 volumio volumio[3421]: ---------------------------- MPD announces system playlist update Oct 14 02:19:55 volumio volumio[3421]: info: Ignoring MPD Status Update Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand clear took 1 milliseconds Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.31.149:8200/MediaItems/1582.wav" Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::servicePushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: 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":"1579.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/1579.wav","trackType":"wav"} Oct 14 02:19:55 volumio volumio[3421]: verbose: CURRENT POSITION 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState stateService stop Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: No code Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: ------------------------------ 38ms Oct 14 02:19:55 volumio volumio[3421]: error: updateQueue error: null Oct 14 02:19:55 volumio volumio[3421]: info: ------------------------------ 43ms Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.31.149:8200/MediaItems/1582.wav" Oct 14 02:19:55 volumio volumio[3421]: info: Oct 14 02:19:55 volumio volumio[3421]: ---------------------------- MPD announces system playlist update Oct 14 02:19:55 volumio volumio[3421]: info: Ignoring MPD Status Update Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand add "http://192.168.31.149:8200/MediaItems/1582.wav" took 1 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand play Oct 14 02:19:55 volumio volumio[3421]: info: ------------------------------ 1ms Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand play took 1 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: Oct 14 02:19:55 volumio volumio[3421]: ---------------------------- MPD announces state update: player Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::getState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:19:55 volumio volumio[3421]: info: Oct 14 02:19:55 volumio volumio[3421]: ---------------------------- MPD announces state update: player Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::getState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand status Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand status took 3 milliseconds Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseState Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::servicePushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1582.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/1582.wav","trackType":"wav"} Oct 14 02:19:55 volumio volumio[3421]: verbose: CURRENT POSITION 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState stateService play Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState currentStatus stop Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: ------------------------------ 9ms Oct 14 02:19:55 volumio volumio[3421]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 02:19:55 volumio volumio[3421]: verbose: ControllerMpd::parseTrackInfo Oct 14 02:19:55 volumio volumio[3421]: info: ControllerMpd::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::servicePushState Oct 14 02:19:55 volumio volumio[3421]: info: CorePlayQueue::getTrack 1069 Oct 14 02:19:55 volumio volumio[3421]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":297,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1582.wav","artist":null,"album":null,"uri":"http://192.168.31.149:8200/MediaItems/1582.wav","trackType":"wav"} Oct 14 02:19:55 volumio volumio[3421]: verbose: CURRENT POSITION 1069 Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState stateService play Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::syncState currentStatus play Oct 14 02:19:55 volumio volumio[3421]: info: Received an update from plugin. extracting info from payload Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: CoreStateMachine::pushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::volumioPushState Oct 14 02:19:55 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 02:19:55 volumio volumio[3421]: info: ------------------------------ 14ms Oct 14 02:20:15 volumio volumio[3421]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 02:20:15 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 02:20:15 volumio volumio[3421]: info: CoreCommandRouter::volumioGetState Oct 14 02:20:15 volumio volumio[3421]: info: CoreCommandRouter::volumioGetQueue Oct 14 02:20:15 volumio volumio[3421]: info: CoreStateMachine::getQueue Oct 14 02:20:15 volumio volumio[3421]: info: CorePlayQueue::getQueue Oct 14 02:20:15 volumio volumio[3421]: info: Listing playlists Oct 14 02:20:15 volumio volumio[3421]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 14 02:20:24 volumio sudo[3460]: pam_unix(sudo:session): session closed for user root Oct 14 02:20:24 volumio volumio[3421]: info: Cannot mount NAS bfq at system boot, trial number 1 ,retrying in 5 seconds Oct 14 02:20:29 volumio sudo[3868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t nfs -o ro,soft,noauto 192.168.31.149:音乐 /mnt/NAS/bfq Oct 14 02:20:29 volumio sudo[3868]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 02:20:56 volumio volumio[3421]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:20:56 volumio systemd[1]: Starting Cleanup of Temporary Directories... Oct 14 02:20:56 volumio volumio[3421]: { Error: connect ETIMEDOUT 199.59.150.12:443 Oct 14 02:20:56 volumio volumio[3421]: at Object._errnoException (util.js:1022:11) Oct 14 02:20:56 volumio volumio[3421]: at _exceptionWithHostPort (util.js:1044:20) Oct 14 02:20:56 volumio volumio[3421]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Oct 14 02:20:56 volumio volumio[3421]: code: 'ETIMEDOUT', Oct 14 02:20:56 volumio volumio[3421]: errno: 'ETIMEDOUT', Oct 14 02:20:56 volumio volumio[3421]: syscall: 'connect', Oct 14 02:20:56 volumio volumio[3421]: address: '199.59.150.12', Oct 14 02:20:56 volumio volumio[3421]: port: 443 } Oct 14 02:20:56 volumio volumio[3421]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 02:20:56 volumio systemd[1]: Started Cleanup of Temporary Directories. Oct 14 02:20:56 volumio sudo[3938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-14 02:19 Oct 14 02:20:56 volumio sudo[3938]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="958dedc0edff114f244fa9acc70cf53089d9e5ae" VOLUMIO_BE_VERSION="307f91893fca011201acba9973e1c41bd3a0ad5d" VOLUMIO_ARCH="x86" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Oct 1 11:49:13 CEST 2021" VOLUMIO_VERSION="2.916" VOLUMIO_HARDWARE="x86" VOLUMIO_HASH="62511ec23417b6085328d5bda7373a0c"