-- Logs begin at Thu 2024-10-03 17:14:20 CST, end at Thu 2024-10-03 17:20:12 CST. -- Oct 03 17:19:03 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 03 17:19:03 volumio-ncu volumio[2339]: info: CURURI: music-library Oct 03 17:19:03 volumio-ncu mpd[2440]: exception: No database Oct 03 17:19:03 volumio-ncu volumio[2339]: error: Failed LSINFO: Error: [50@0] {lsinfo} No database Oct 03 17:19:03 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:08 volumio-ncu volumio-remote-updater[856]: No test mode Oct 03 17:19:08 volumio-ncu volumio-remote-updater[856]: No alpha test mode Oct 03 17:19:08 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 03 17:19:09 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 03 17:19:09 volumio-ncu volumio[2339]: info: CURURI: albums:// Oct 03 17:19:09 volumio-ncu mpd[2440]: exception: No database Oct 03 17:19:09 volumio-ncu volumio[2339]: error: Failed to execute browseSource: Error: Cannot list albums Oct 03 17:19:13 volumio-ncu volumio[2339]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 03 17:19:13 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 03 17:19:16 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 03 17:19:16 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:16 volumio-ncu volumio[2339]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 03 17:19:16 volumio-ncu volumio[2339]: info: Retrying Login Due to Network Error Oct 03 17:19:18 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 03 17:19:18 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:20 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 03 17:19:20 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:22 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 03 17:19:22 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:25 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 03 17:19:25 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:25 volumio-ncu volumio[2339]: info: Preloading song: upnp/http://192.168.5.254:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/l/231/t/4606 Oct 03 17:19:25 volumio-ncu volumio[2339]: info: Exploding uri upnp/http://192.168.5.254:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/l/231/t/4606 in service upnp_browser Oct 03 17:19:26 volumio-ncu volumio[2339]: info: MyVolumio login type: Token Oct 03 17:19:27 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreStateMachine::ClearQueue Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreStateMachine::stop Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CorePlayQueue::clearPlayQueue Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CorePlayQueue::saveQueue Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CoreStateMachine::addQueueItems Oct 03 17:19:27 volumio-ncu volumio[2339]: info: CorePlayQueue::addQueueItems Oct 03 17:19:27 volumio-ncu volumio[2339]: info: Preload queue cleared Oct 03 17:19:27 volumio-ncu volumio[2339]: info: Adding Item to queue: upnp/folder/http://192.168.5.254:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/l/231/t Oct 03 17:19:27 volumio-ncu volumio[2339]: info: Exploding uri upnp/folder/http://192.168.5.254:9000/plugins/UPnP/MediaServer/ContentDirectory/control@/l/231/t in service upnp_browser Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushQueue Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::saveQueue Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::updateTrackBlock Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrackBlock Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPlay Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::play index 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::stop Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::play index undefined Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::startPlaybackTimer Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: [1727947168223] ControllerUPNPBrowser::clearAddPlayTrack Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand stop took 2 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand clear Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand clear took 2 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.5.254:9000/music/4606/download.flc" Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu mpd[2440]: exception: No such playlist Oct 03 17:19:28 volumio-ncu volumio[2339]: error: updateQueue error: null Oct 03 17:19:28 volumio-ncu volumio[2339]: error: updateQueue error: null Oct 03 17:19:28 volumio-ncu volumio[2339]: error: updateQueue error: null Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 20ms Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.5.254:9000/music/4606/download.flc" Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 18ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 17ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand add "http://192.168.5.254:9000/music/4606/download.flc" took 2 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 6ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand play took 5 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 4ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 3ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 36 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 40 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 39 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus stop Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 54ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces system playlist update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Ignoring MPD Status Update Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Oct 03 17:19:28 volumio-ncu volumio[2339]: ---------------------------- MPD announces state update: player Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::getState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand status Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 23ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 22 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 13ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 11 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 11ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand status took 6 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseState Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Received an update from plugin. extracting info from payload Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Received an update from plugin. extracting info from payload Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 131ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 130ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 72 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 74 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: info: sendMpdCommand playlistinfo took 73 milliseconds Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: ControllerMpd::parseTrackInfo Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Received an update from plugin. extracting info from payload Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Received an update from plugin. extracting info from payload Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ControllerMpd::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::servicePushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CorePlayQueue::getTrack 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: STATE SERVICE {"status":"play","position":0,"seek":227,"duration":267,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"全想试音碟4","artist":null,"album":"白月光","uri":"http://192.168.5.254:9000/music/4606/download.flc","trackType":"flc"} Oct 03 17:19:28 volumio-ncu volumio[2339]: verbose: CURRENT POSITION 0 Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState stateService play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::syncState currentStatus play Oct 03 17:19:28 volumio-ncu volumio[2339]: info: Received an update from plugin. extracting info from payload Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreStateMachine::pushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioPushState Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 191ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 179ms Oct 03 17:19:28 volumio-ncu volumio[2339]: info: ------------------------------ 174ms Oct 03 17:19:30 volumio-ncu nmbd[1006]: [2024/10/03 17:19:30.379935, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 03 17:19:30 volumio-ncu nmbd[1006]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.5.112 for name WORKGROUP<1d>. Oct 03 17:19:30 volumio-ncu nmbd[1006]: This response was from IP 192.168.5.254, reporting an IP address of 192.168.5.254. Oct 03 17:19:30 volumio-ncu nmbd[1006]: [2024/10/03 17:19:30.380476, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Oct 03 17:19:30 volumio-ncu nmbd[1006]: query_name_response: Multiple (3) responses received for a query on subnet 192.168.5.112 for name WORKGROUP<1d>. Oct 03 17:19:30 volumio-ncu nmbd[1006]: This response was from IP 192.168.5.254, reporting an IP address of 192.168.5.254. Oct 03 17:19:45 volumio-ncu kernel: r8169 0000:03:00.0 eth0: Link is Down Oct 03 17:19:45 volumio-ncu dhcpcd[1482]: eth0: carrier lost Oct 03 17:19:45 volumio-ncu dhcpcd[915]: eth0: carrier lost Oct 03 17:19:45 volumio-ncu dnsmasq[1256]: no servers found in /etc/resolv.conf, will retry Oct 03 17:19:45 volumio-ncu avahi-daemon[871]: Withdrawing address record for 192.168.5.112 on eth0. Oct 03 17:19:45 volumio-ncu avahi-daemon[871]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.5.112. Oct 03 17:19:45 volumio-ncu avahi-daemon[871]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 03 17:19:45 volumio-ncu volumio[2339]: info: Discovery: A device disappeared from network Oct 03 17:19:45 volumio-ncu volumio[2339]: info: Discovery: Device volumio-ncu disappeared from network Oct 03 17:19:45 volumio-ncu dhcpcd[1482]: eth0: deleting route to 192.168.5.0/24 Oct 03 17:19:45 volumio-ncu dhcpcd[1482]: eth0: deleting default route via 192.168.5.1 Oct 03 17:19:45 volumio-ncu dhcpcd[915]: wlan0: adding default route Oct 03 17:19:45 volumio-ncu dhcpcd[915]: eth0: deleting route to 192.168.5.0/24 Oct 03 17:19:45 volumio-ncu dhcpcd[915]: eth0: deleting default route via 192.168.5.1 Oct 03 17:19:46 volumio-ncu ifplugd(eth0)[1047]: Link beat lost. Oct 03 17:19:46 volumio-ncu ntpd[1618]: Deleting interface #3 eth0, 192.168.5.112#123, interface stats: received=65, sent=79, dropped=0, active_time=310 secs Oct 03 17:19:46 volumio-ncu ntpd[1618]: 193.182.111.142 local addr 192.168.5.112 -> Oct 03 17:19:46 volumio-ncu ntpd[1618]: 111.230.189.174 local addr 192.168.5.112 -> Oct 03 17:19:46 volumio-ncu ntpd[1618]: 117.80.231.60 local addr 192.168.5.112 -> Oct 03 17:19:46 volumio-ncu ntpd[1618]: 202.118.1.81 local addr 192.168.5.112 -> Oct 03 17:19:46 volumio-ncu ntpd[1618]: 172.105.240.243 local addr 192.168.5.112 -> Oct 03 17:19:46 volumio-ncu ntpd[1618]: 116.203.151.74 local addr 192.168.5.112 -> Oct 03 17:19:50 volumio-ncu kernel: r8169 0000:03:00.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Oct 03 17:19:50 volumio-ncu dhcpcd[915]: eth0: carrier acquired Oct 03 17:19:50 volumio-ncu dhcpcd[1482]: eth0: carrier acquired Oct 03 17:19:50 volumio-ncu dhcpcd[915]: eth0: IAID 30:6b:50:f2 Oct 03 17:19:50 volumio-ncu dhcpcd[1482]: eth0: IAID 30:6b:50:f2 Oct 03 17:19:50 volumio-ncu ifplugd(eth0)[1047]: Link beat detected. Oct 03 17:19:50 volumio-ncu dhcpcd[915]: eth0: rebinding lease of 192.168.5.112 Oct 03 17:19:50 volumio-ncu dhcpcd[1482]: eth0: soliciting an IPv6 router Oct 03 17:19:50 volumio-ncu dhcpcd[915]: eth0: soliciting an IPv6 router Oct 03 17:19:50 volumio-ncu dhcpcd[1482]: eth0: rebinding lease of 192.168.5.112 Oct 03 17:19:54 volumio-ncu nmbd[1006]: [2024/10/03 17:19:54.422788, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 03 17:19:54 volumio-ncu nmbd[1006]: Packet send failed to 192.168.5.255(138) ERRNO=Network is unreachable Oct 03 17:19:54 volumio-ncu dhcpcd[915]: eth0: probing address 192.168.5.112/24 Oct 03 17:19:55 volumio-ncu dhcpcd[1482]: eth0: probing address 192.168.5.112/24 Oct 03 17:19:57 volumio-ncu volumio[2339]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Oct 03 17:19:57 volumio-ncu volumio[2339]: info: Retrying Login Due to Network Error Oct 03 17:19:59 volumio-ncu dhcpcd[915]: eth0: leased 192.168.5.112 for 7200 seconds Oct 03 17:19:59 volumio-ncu dhcpcd[915]: eth0: adding route to 192.168.5.0/24 Oct 03 17:19:59 volumio-ncu dhcpcd[915]: eth0: adding default route via 192.168.5.1 Oct 03 17:19:59 volumio-ncu dhcpcd[915]: wlan0: deleting default route Oct 03 17:19:59 volumio-ncu avahi-daemon[871]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.5.112. Oct 03 17:19:59 volumio-ncu avahi-daemon[871]: New relevant interface eth0.IPv4 for mDNS. Oct 03 17:19:59 volumio-ncu avahi-daemon[871]: Registering new address record for 192.168.5.112 on eth0.IPv4. Oct 03 17:19:59 volumio-ncu dnsmasq[1256]: reading /etc/resolv.conf Oct 03 17:19:59 volumio-ncu dnsmasq[1256]: using nameserver 202.102.154.3#53 Oct 03 17:19:59 volumio-ncu dnsmasq[1256]: using nameserver 202.102.152.3#53 Oct 03 17:19:59 volumio-ncu dnsmasq[1256]: using nameserver 208.67.222.222#53 Oct 03 17:19:59 volumio-ncu dnsmasq[1256]: using nameserver 208.67.220.220#53 Oct 03 17:19:59 volumio-ncu systemd[1]: Stopping Network Time Service... Oct 03 17:19:59 volumio-ncu ntpd[1618]: ntpd exiting on signal 15 (Terminated) Oct 03 17:19:59 volumio-ncu ntpd[1618]: 116.203.151.74 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu ntpd[1618]: 172.105.240.243 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu ntpd[1618]: 202.118.1.81 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu ntpd[1618]: 117.80.231.60 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu ntpd[1618]: 111.230.189.174 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu ntpd[1618]: 193.182.111.142 local addr 169.254.223.178 -> Oct 03 17:19:59 volumio-ncu systemd[1]: ntp.service: Succeeded. Oct 03 17:19:59 volumio-ncu systemd[1]: Stopped Network Time Service. Oct 03 17:19:59 volumio-ncu systemd[1]: Starting Network Time Service... Oct 03 17:19:59 volumio-ncu ntpd[2717]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 03 17:19:59 volumio-ncu ntpd[2717]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 03 17:19:59 volumio-ncu systemd[1]: Started Network Time Service. Oct 03 17:19:59 volumio-ncu ntpd[2723]: proto: precision = 0.187 usec (-22) Oct 03 17:19:59 volumio-ncu ntpd[2723]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 03 17:19:59 volumio-ncu ntpd[2723]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 03 17:19:59 volumio-ncu ntpd[2723]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 646 days ago Oct 03 17:19:59 volumio-ncu ntpd[2723]: Listen and drop on 0 v6wildcard [::]:123 Oct 03 17:19:59 volumio-ncu ntpd[2723]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 03 17:20:00 volumio-ncu ntpd[2723]: Listen normally on 2 lo 127.0.0.1:123 Oct 03 17:20:00 volumio-ncu ntpd[2723]: Listen normally on 3 eth0 192.168.5.112:123 Oct 03 17:20:00 volumio-ncu ntpd[2723]: Listen normally on 4 wlan0 192.168.211.1:123 Oct 03 17:20:00 volumio-ncu ntpd[2723]: Listen normally on 5 wlan0 169.254.223.178:123 Oct 03 17:20:00 volumio-ncu ntpd[2723]: Listening on routing socket on fd #22 for interface updates Oct 03 17:20:00 volumio-ncu ntpd[2723]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 03 17:20:00 volumio-ncu ntpd[2723]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 03 17:20:00 volumio-ncu dhcpcd[1482]: eth0: leased 192.168.5.112 for 7200 seconds Oct 03 17:20:00 volumio-ncu dhcpcd[1482]: eth0: adding route to 192.168.5.0/24 Oct 03 17:20:00 volumio-ncu dhcpcd[1482]: eth0: adding default route via 192.168.5.1 Oct 03 17:20:00 volumio-ncu systemd[1]: Stopping Network Time Service... Oct 03 17:20:00 volumio-ncu ntpd[2723]: ntpd exiting on signal 15 (Terminated) Oct 03 17:20:00 volumio-ncu systemd[1]: ntp.service: Succeeded. Oct 03 17:20:00 volumio-ncu systemd[1]: Stopped Network Time Service. Oct 03 17:20:00 volumio-ncu systemd[1]: Starting Network Time Service... Oct 03 17:20:00 volumio-ncu ntpd[2757]: ntpd 4.2.8p12@1.3728-o (1): Starting Oct 03 17:20:00 volumio-ncu ntpd[2757]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Oct 03 17:20:00 volumio-ncu systemd[1]: Started Network Time Service. Oct 03 17:20:00 volumio-ncu ntpd[2763]: proto: precision = 0.188 usec (-22) Oct 03 17:20:00 volumio-ncu ntpd[2763]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Oct 03 17:20:00 volumio-ncu ntpd[2763]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Oct 03 17:20:00 volumio-ncu ntpd[2763]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 646 days ago Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen and drop on 0 v6wildcard [::]:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen normally on 2 lo 127.0.0.1:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen normally on 3 eth0 192.168.5.112:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen normally on 4 wlan0 192.168.211.1:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listen normally on 5 wlan0 169.254.223.178:123 Oct 03 17:20:00 volumio-ncu ntpd[2763]: Listening on routing socket on fd #22 for interface updates Oct 03 17:20:00 volumio-ncu ntpd[2763]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 03 17:20:00 volumio-ncu ntpd[2763]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Oct 03 17:20:00 volumio-ncu volumio[2339]: info: Discovery: adding c2d99f2f-56d9-4e51-b835-6a2e1c508756 Oct 03 17:20:00 volumio-ncu volumio[2339]: info: Discovery: Found device Volumio-NCU Oct 03 17:20:00 volumio-ncu volumio[2339]: info: CoreCommandRouter::volumioGetState Oct 03 17:20:01 volumio-ncu ntpd[2763]: Soliciting pool server 202.112.31.197 Oct 03 17:20:02 volumio-ncu ntpd[2763]: Soliciting pool server 162.159.200.1 Oct 03 17:20:02 volumio-ncu ntpd[2763]: Soliciting pool server 202.118.1.81 Oct 03 17:20:03 volumio-ncu ntpd[2763]: Soliciting pool server 202.112.29.82 Oct 03 17:20:03 volumio-ncu ntpd[2763]: Soliciting pool server 117.80.231.60 Oct 03 17:20:03 volumio-ncu ntpd[2763]: Soliciting pool server 84.16.67.12 Oct 03 17:20:04 volumio-ncu ntpd[2763]: Soliciting pool server 193.182.111.143 Oct 03 17:20:04 volumio-ncu ntpd[2763]: Soliciting pool server 111.203.6.13 Oct 03 17:20:04 volumio-ncu ntpd[2763]: Soliciting pool server 162.159.200.123 Oct 03 17:20:04 volumio-ncu ntpd[2763]: Soliciting pool server 202.118.1.130 Oct 03 17:20:05 volumio-ncu ntpd[2763]: Soliciting pool server 116.203.151.74 Oct 03 17:20:05 volumio-ncu ntpd[2763]: Soliciting pool server 78.46.102.180 Oct 03 17:20:06 volumio-ncu ntpd[2763]: Soliciting pool server 193.182.111.141 Oct 03 17:20:06 volumio-ncu ntpd[2763]: Soliciting pool server 84.16.73.33 Oct 03 17:20:07 volumio-ncu volumio[2339]: info: MyVolumio login type: Token Oct 03 17:20:12 volumio-ncu volumio[2339]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:20:12 volumio-ncu volumio[2339]: Error: connect ETIMEDOUT 104.244.46.52:443 Oct 03 17:20:12 volumio-ncu volumio[2339]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 03 17:20:12 volumio-ncu volumio[2339]: errno: -110, Oct 03 17:20:12 volumio-ncu volumio[2339]: code: 'ETIMEDOUT', Oct 03 17:20:12 volumio-ncu volumio[2339]: syscall: 'connect', Oct 03 17:20:12 volumio-ncu volumio[2339]: address: '104.244.46.52', Oct 03 17:20:12 volumio-ncu volumio[2339]: port: 443 Oct 03 17:20:12 volumio-ncu volumio[2339]: } Oct 03 17:20:12 volumio-ncu volumio[2339]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 17:20:12 volumio-ncu sudo[2780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-03 17:19 Oct 03 17:20:12 volumio-ncu sudo[2780]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"