-- Logs begin at Wed 2026-10-14 17:54:14 CST, end at Wed 2026-10-14 18:05:45 CST. -- Oct 14 18:04:03 volumio sshd[3922]: Accepted password for volumio from 192.168.3.2 port 61947 ssh2 Oct 14 18:04:03 volumio sshd[3922]: pam_unix(sshd:session): session opened for user volumio by (uid=0) Oct 14 18:04:03 volumio systemd-logind[772]: New session 3 of user volumio. Oct 14 18:04:03 volumio systemd[1]: Started Session 3 of user volumio. Oct 14 18:04:04 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:04 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:09 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:09 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:14 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:14 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:19 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:19 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:24 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:24 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:25 volumio ntpd[1123]: 95.111.202.5 local addr 192.168.3.83 -> Oct 14 18:04:29 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:29 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:34 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:34 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:39 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:39 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:44 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:44 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:49 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:49 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:55 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:04:55 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:04:56 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 14 18:04:56 volumio volumio[1134]: info: Preload queue cleared Oct 14 18:04:56 volumio ntpd[1123]: 193.182.111.14 local addr 192.168.3.83 -> Oct 14 18:04:57 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 14 18:04:57 volumio volumio[1134]: info: Preload queue cleared Oct 14 18:05:00 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:00 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:03 volumio sshd[3922]: pam_unix(sshd:session): session closed for user volumio Oct 14 18:05:03 volumio systemd[1]: session-3.scope: Succeeded. Oct 14 18:05:03 volumio systemd-logind[772]: Session 3 logged out. Waiting for processes to exit. Oct 14 18:05:03 volumio systemd-logind[772]: Removed session 3. Oct 14 18:05:05 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:05 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:10 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:10 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:15 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:15 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:20 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:20 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:25 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:25 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:27 volumio volumio[1134]: info: Preload queue cleared Oct 14 18:05:27 volumio volumio[1134]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::ClearQueue Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::stop Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::clearPlayQueue Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::saveQueue Oct 14 18:05:27 volumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::addQueueItems Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::addQueueItems Oct 14 18:05:27 volumio volumio[1134]: info: Preload queue cleared Oct 14 18:05:27 volumio volumio[1134]: info: Adding Item to queue: https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8 Oct 14 18:05:27 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 14 18:05:27 volumio volumio[1134]: info: CoreCommandRouter::volumioPushQueue Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::saveQueue Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::getTrackBlock Oct 14 18:05:27 volumio volumio[1134]: info: CoreCommandRouter::volumioPlay Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::play index 0 Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::stop Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::play index undefined Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::startPlaybackTimer Oct 14 18:05:27 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:27 volumio volumio[1134]: info: [1791972327069] ControllerWebradio::clearAddPlayTrack Oct 14 18:05:27 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 18:05:27 volumio volumio[1134]: info: sendMpdCommand stop took 2 milliseconds Oct 14 18:05:27 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 18:05:27 volumio volumio[1134]: info: sendMpdCommand clear took 0 milliseconds Oct 14 18:05:27 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand add "https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8" Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: sendMpdCommand add "https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8" took 1 milliseconds Oct 14 18:05:27 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 14 18:05:27 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand play Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 5ms Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 4ms Oct 14 18:05:27 volumio volumio[1134]: info: sendMpdCommand play took 3 milliseconds Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 3ms Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 2ms Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 2ms Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: info: Oct 14 18:05:27 volumio volumio[1134]: ---------------------------- MPD announces system playlist update Oct 14 18:05:27 volumio volumio[1134]: info: Ignoring MPD Status Update Oct 14 18:05:27 volumio volumio[1134]: error: Upnp client error: Error: This socket has been ended by the other party Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 2ms Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 1ms Oct 14 18:05:27 volumio volumio[1134]: info: ------------------------------ 1ms Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 17 milliseconds Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 18 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 18 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 17 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus stop Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 23ms Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: Oct 14 18:05:28 volumio volumio[1134]: ---------------------------- MPD announces state update: player Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::getState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand status Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 6 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 3 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 2 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand status took 1 milliseconds Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseState Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":178,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":261,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":303,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 45ms Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 44ms Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 44ms Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 18:05:28 volumio volumio[1134]: info: sendMpdCommand playlistinfo took 20 milliseconds Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: verbose: ControllerMpd::parseTrackInfo Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":669,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":669,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":669,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ControllerMpd::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::servicePushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: verbose: STATE SERVICE {"status":"play","position":0,"seek":669,"duration":0,"samplerate":48,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"cctv1_2.m3u8","artist":null,"album":null,"uri":"https://piccpndali.v.myalicdn.com/audio/cctv1_2.m3u8","trackType":"m3u8"} Oct 14 18:05:28 volumio volumio[1134]: verbose: CURRENT POSITION 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState stateService play Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::syncState currentStatus play Oct 14 18:05:28 volumio volumio[1134]: info: Received an update from plugin. extracting info from payload Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:28 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 58ms Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 54ms Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 54ms Oct 14 18:05:28 volumio volumio[1134]: info: ------------------------------ 53ms Oct 14 18:05:30 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:30 volumio sudo[4294]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/bin/bash run_peppymeter.sh Oct 14 18:05:30 volumio sudo[4294]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 14 18:05:32 volumio volumio[1134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 7 Oct 14 18:05:32 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:32 volumio volumio[1134]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Engine version: 3 Transport: polling Total Clients: 8 Oct 14 18:05:32 volumio volumio[1134]: info: CoreCommandRouter::volumioGetState Oct 14 18:05:41 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 14 18:05:41 volumio volumio[1134]: info: Preload queue cleared Oct 14 18:05:44 volumio volumio[1134]: info: CoreCommandRouter::volumioStop Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::stop Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::stPlaybackTimer Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::updateTrackBlock Oct 14 18:05:44 volumio volumio[1134]: info: CorePlayQueue::getTrackBlock Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::pushState Oct 14 18:05:44 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:44 volumio volumio[1134]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 18:05:44 volumio volumio[1134]: info: CoreCommandRouter::volumioPushState Oct 14 18:05:44 volumio volumio[1134]: info: CoreStateMachine::serviceStop Oct 14 18:05:44 volumio volumio[1134]: info: CorePlayQueue::getTrack 0 Oct 14 18:05:44 volumio volumio[1134]: info: CoreCommandRouter::serviceStop Oct 14 18:05:44 volumio volumio[1134]: info: [1791972344925] ControllerWebradio::stop Oct 14 18:05:44 volumio volumio[1134]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 18:05:44 volumio volumio[1134]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 18:05:44 volumio volumio[1134]: Error: ENOTDIR: not a directory, scandir '/tmp/peppyrunning' Oct 14 18:05:44 volumio volumio[1134]: at Object.readdirSync (fs.js:1048:3) Oct 14 18:05:44 volumio volumio[1134]: at rmkidsSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:349:11) Oct 14 18:05:44 volumio volumio[1134]: at rmdirSync (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:342:7) Oct 14 18:05:44 volumio volumio[1134]: at Function.rimrafSync [as sync] (/data/plugins/user_interface/peppy_screensaver/node_modules/rimraf/rimraf.js:319:62) Oct 14 18:05:44 volumio volumio[1134]: at Object.removeSync (/data/plugins/user_interface/peppy_screensaver/node_modules/fs-extra/lib/remove/index.js:4:17) Oct 14 18:05:44 volumio volumio[1134]: at Socket. (/data/plugins/user_interface/peppy_screensaver/index.js:225:48) Oct 14 18:05:44 volumio volumio[1134]: at Socket.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 14 18:05:44 volumio volumio[1134]: at Socket.onevent (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:278:10) Oct 14 18:05:44 volumio volumio[1134]: at Socket.onpacket (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/socket.js:236:12) Oct 14 18:05:44 volumio volumio[1134]: at Manager. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 14 18:05:44 volumio volumio[1134]: at Manager.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 14 18:05:44 volumio volumio[1134]: at Manager.ondecoded (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:349:8) Oct 14 18:05:44 volumio volumio[1134]: at Decoder. (/data/plugins/user_interface/peppy_screensaver/node_modules/component-bind/index.js:21:15) Oct 14 18:05:44 volumio volumio[1134]: at Decoder.Emitter.emit (/data/plugins/user_interface/peppy_screensaver/node_modules/component-emitter/index.js:145:20) Oct 14 18:05:44 volumio volumio[1134]: at Decoder.add (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-parser/index.js:251:12) Oct 14 18:05:44 volumio volumio[1134]: at Manager.ondata (/data/plugins/user_interface/peppy_screensaver/node_modules/socket.io-client/lib/manager.js:339:16) { Oct 14 18:05:44 volumio volumio[1134]: errno: -20, Oct 14 18:05:44 volumio volumio[1134]: syscall: 'scandir', Oct 14 18:05:44 volumio volumio[1134]: code: 'ENOTDIR', Oct 14 18:05:44 volumio volumio[1134]: path: '/tmp/peppyrunning' Oct 14 18:05:44 volumio volumio[1134]: } Oct 14 18:05:44 volumio volumio[1134]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 18:05:45 volumio sudo[4390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-10-14 18:04 Oct 14 18:05:45 volumio sudo[4390]: 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="49c352e1d55e9b76c3bd7b0e3940507619bf455a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="1d32690fc900ac8c739e7eabd35ed0f570899eb8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 19 Dec 2025 02:40:03 PM CET" VOLUMIO_VERSION="3.887" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="05e5ed27b04d8d1e8a8a87ca324a64c8"