-- Logs begin at Tue 2025-09-16 16:31:14 CST, end at Tue 2025-09-16 16:41:37 CST. -- Sep 16 16:40:02 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:40:02 volumio sudo[3190]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Sep 16 16:40:02 volumio sudo[3190]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:40:04 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 7 Sep 16 16:40:05 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 8 Sep 16 16:40:05 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:40:19 volumio screensaver.sh[447]: libpng warning: iCCP: known incorrect sRGB profile Sep 16 16:40:19 volumio screensaver.sh[447]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 16 16:40:20 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 9 Sep 16 16:40:21 volumio screensaver.sh[447]: libpng warning: iCCP: known incorrect sRGB profile Sep 16 16:40:21 volumio screensaver.sh[447]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 16 16:40:22 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 Sep 16 16:40:22 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:40:34 volumio kernel: usb 6-4: reset high-speed USB device number 2 using ehci-pci Sep 16 16:40:38 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 10 Sep 16 16:40:39 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 11 Sep 16 16:40:39 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:40:55 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 12 Sep 16 16:40:56 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 13 Sep 16 16:40:56 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:41:05 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::ClearQueue Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::stPlaybackTimer Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::serviceStop Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::serviceStop Sep 16 16:41:05 volumio volumio[2539]: info: [1758012065687] ControllerWebradio::stop Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::clearPlayQueue Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::addQueueItems Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::addQueueItems Sep 16 16:41:05 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:05 volumio volumio[2539]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978 Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:05 volumio volumio[2539]: info: CoreCommandRouter::volumioPlay Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::play index 0 Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::play index undefined Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:05 volumio volumio[2539]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:41:05 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:05 volumio volumio[2539]: info: [1758012065716] ControllerWebradio::clearAddPlayTrack Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:05 volumio volumio[2539]: info: Sep 16 16:41:05 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:05 volumio volumio[2539]: info: sendMpdCommand stop took 35 milliseconds Sep 16 16:41:05 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:05 volumio volumio[2539]: info: sendMpdCommand stop took 7 milliseconds Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clear Sep 16 16:41:05 volumio volumio[2539]: info: Sep 16 16:41:05 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:05 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:05 volumio volumio[2539]: info: sendMpdCommand status took 2 milliseconds Sep 16 16:41:05 volumio volumio[2539]: info: sendMpdCommand clear took 1 milliseconds Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" Sep 16 16:41:05 volumio volumio[2539]: error: updateQueue error: null Sep 16 16:41:05 volumio volumio[2539]: info: ------------------------------ 5ms Sep 16 16:41:05 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 16 16:41:05 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:05 volumio volumio[2539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 16 16:41:05 volumio volumio[2539]: info: ------------------------------ 9ms Sep 16 16:41:05 volumio sudo[3190]: pam_unix(sudo:session): session closed for user root Sep 16 16:41:06 volumio volumio[2539]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1206978" took 734 milliseconds Sep 16 16:41:06 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 16:41:06 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand play Sep 16 16:41:06 volumio volumio[2539]: info: Sep 16 16:41:06 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:06 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:06 volumio volumio[2539]: info: sendMpdCommand play took 1 milliseconds Sep 16 16:41:06 volumio volumio[2539]: info: ------------------------------ 1ms Sep 16 16:41:07 volumio volumio[2539]: info: Sep 16 16:41:07 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:07 volumio volumio[2539]: info: Sep 16 16:41:07 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand status took 17 milliseconds Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:07 volumio volumio[2539]: info: Sep 16 16:41:07 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:07 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:07 volumio volumio[2539]: info: Sep 16 16:41:07 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand status took 4 milliseconds Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:07 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:07 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"78.129.202.10:8030","artist":"(#1 - 290/5000) Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Sep 16 16:41:07 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:41:07 volumio volumio[2539]: info: ------------------------------ 26ms Sep 16 16:41:07 volumio volumio[2539]: info: ------------------------------ 5ms Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand status took 3 milliseconds Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:07 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:07 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Romantics - What I Like About You","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Sep 16 16:41:07 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:07 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:07 volumio volumio[2539]: info: ------------------------------ 30ms Sep 16 16:41:07 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 27 milliseconds Sep 16 16:41:07 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:07 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:07 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:07 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"92 Kbps","isStreaming":false,"title":"The Romantics - What I Like About You","artist":"Radio Caroline","album":null,"uri":"http://78.129.202.10:8030","trackType":"10:8030"} Sep 16 16:41:07 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:07 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:07 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:07 volumio volumio[2539]: info: ------------------------------ 49ms Sep 16 16:41:12 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::ClearQueue Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::stPlaybackTimer Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::serviceStop Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::serviceStop Sep 16 16:41:12 volumio volumio[2539]: info: [1758012072907] ControllerWebradio::stop Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::clearPlayQueue Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::addQueueItems Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::addQueueItems Sep 16 16:41:12 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:12 volumio volumio[2539]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99582579 Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:12 volumio volumio[2539]: info: CoreCommandRouter::volumioPlay Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::play index 0 Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::play index undefined Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:12 volumio volumio[2539]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:41:12 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:12 volumio volumio[2539]: info: [1758012072923] ControllerWebradio::clearAddPlayTrack Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:12 volumio volumio[2539]: info: Sep 16 16:41:12 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:12 volumio volumio[2539]: info: sendMpdCommand stop took 21 milliseconds Sep 16 16:41:12 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:12 volumio volumio[2539]: info: sendMpdCommand stop took 6 milliseconds Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clear Sep 16 16:41:12 volumio volumio[2539]: info: Sep 16 16:41:12 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:12 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:12 volumio volumio[2539]: info: sendMpdCommand status took 2 milliseconds Sep 16 16:41:12 volumio volumio[2539]: info: sendMpdCommand clear took 2 milliseconds Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99582579" Sep 16 16:41:12 volumio volumio[2539]: error: updateQueue error: null Sep 16 16:41:12 volumio volumio[2539]: info: ------------------------------ 2ms Sep 16 16:41:12 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:12 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:12 volumio volumio[2539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 16 16:41:12 volumio volumio[2539]: info: ------------------------------ 5ms Sep 16 16:41:13 volumio volumio[2539]: info: Sep 16 16:41:13 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:13 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:13 volumio volumio[2539]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99582579" took 280 milliseconds Sep 16 16:41:13 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 16:41:13 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand play Sep 16 16:41:13 volumio volumio[2539]: info: ------------------------------ 2ms Sep 16 16:41:13 volumio volumio[2539]: info: sendMpdCommand play took 1 milliseconds Sep 16 16:41:16 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:41:16 volumio sudo[3295]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py Sep 16 16:41:16 volumio sudo[3295]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 16:41:18 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 7 Sep 16 16:41:18 volumio screensaver.sh[447]: libpng warning: iCCP: known incorrect sRGB profile Sep 16 16:41:18 volumio screensaver.sh[447]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 16 16:41:19 volumio volumio[2539]: info: Sep 16 16:41:19 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:19 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:19 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:19 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 16 16:41:19 volumio volumio[2539]: info: sendMpdCommand status took 7 milliseconds Sep 16 16:41:19 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:19 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:19 volumio volumio[2539]: info: sendMpdCommand clearerror took 8 milliseconds Sep 16 16:41:19 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 16 16:41:19 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:19 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:19 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:19 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:19 volumio volumio[2539]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"11.025 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"radio.mp3","artist":"(#1 - 489/4000) OK BOEM Radio","album":null,"uri":"https://stream.okplus.rs:8060/radio.mp3","trackType":"mp3"} Sep 16 16:41:19 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:19 volumio volumio[2539]: info: CoreStateMachine::syncState stateService pause Sep 16 16:41:19 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:41:19 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:19 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:19 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:19 volumio volumio[2539]: info: ------------------------------ 52ms Sep 16 16:41:20 volumio screensaver.sh[447]: libpng warning: iCCP: known incorrect sRGB profile Sep 16 16:41:20 volumio screensaver.sh[447]: libpng warning: Interlace handling should be turned on when using png_read_image Sep 16 16:41:20 volumio volumio[2539]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 8 Sep 16 16:41:20 volumio volumio[2539]: info: CoreCommandRouter::volumioGetState Sep 16 16:41:23 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:23 volumio volumio[2539]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::ClearQueue Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::clearPlayQueue Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:23 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::addQueueItems Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::addQueueItems Sep 16 16:41:23 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:23 volumio volumio[2539]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400 Sep 16 16:41:23 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 16 16:41:23 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:23 volumio volumio[2539]: info: CoreCommandRouter::volumioPlay Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::play index 0 Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::play index undefined Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:41:23 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:23 volumio volumio[2539]: info: [1758012083455] ControllerWebradio::clearAddPlayTrack Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:23 volumio volumio[2539]: info: Sep 16 16:41:23 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand stop took 5 milliseconds Sep 16 16:41:23 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clear Sep 16 16:41:23 volumio volumio[2539]: info: Sep 16 16:41:23 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:23 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand clear took 1 milliseconds Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" Sep 16 16:41:23 volumio volumio[2539]: error: updateQueue error: null Sep 16 16:41:23 volumio volumio[2539]: info: ------------------------------ 2ms Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:23 volumio volumio[2539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 16 16:41:23 volumio volumio[2539]: info: ------------------------------ 3ms Sep 16 16:41:23 volumio volumio[2539]: info: Sep 16 16:41:23 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:23 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" took 291 milliseconds Sep 16 16:41:23 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 16:41:23 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand play Sep 16 16:41:23 volumio volumio[2539]: info: ------------------------------ 1ms Sep 16 16:41:23 volumio volumio[2539]: info: sendMpdCommand play took 1 milliseconds Sep 16 16:41:24 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:24 volumio volumio[2539]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::ClearQueue Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::clearPlayQueue Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:24 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::addQueueItems Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::addQueueItems Sep 16 16:41:24 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:24 volumio volumio[2539]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400 Sep 16 16:41:24 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 16 16:41:24 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:24 volumio volumio[2539]: info: CoreCommandRouter::volumioPlay Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::play index 0 Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::play index undefined Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:24 volumio volumio[2539]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:41:24 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:24 volumio volumio[2539]: info: [1758012084967] ControllerWebradio::clearAddPlayTrack Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:24 volumio volumio[2539]: info: Sep 16 16:41:24 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:24 volumio volumio[2539]: info: sendMpdCommand stop took 3 milliseconds Sep 16 16:41:24 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clear Sep 16 16:41:24 volumio volumio[2539]: info: Sep 16 16:41:24 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:24 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:24 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:24 volumio volumio[2539]: info: sendMpdCommand clear took 1 milliseconds Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" Sep 16 16:41:24 volumio volumio[2539]: error: updateQueue error: null Sep 16 16:41:24 volumio volumio[2539]: info: ------------------------------ 1ms Sep 16 16:41:24 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 3 milliseconds Sep 16 16:41:24 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:24 volumio volumio[2539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 16 16:41:24 volumio volumio[2539]: info: ------------------------------ 5ms Sep 16 16:41:25 volumio kernel: usb 6-4: reset high-speed USB device number 2 using ehci-pci Sep 16 16:41:25 volumio volumio[2539]: info: Sep 16 16:41:25 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:25 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" took 254 milliseconds Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand play Sep 16 16:41:25 volumio volumio[2539]: info: ------------------------------ 1ms Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand play took 1 milliseconds Sep 16 16:41:25 volumio volumio[2539]: info: Sep 16 16:41:25 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:25 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:25 volumio volumio[2539]: info: Sep 16 16:41:25 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand status took 20 milliseconds Sep 16 16:41:25 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 16 16:41:25 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:25 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:25 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:25 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:25 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:25 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 824/3500) Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:25 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:41:25 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:25 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:25 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:25 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 824/3500) Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:25 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:25 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:25 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:25 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:25 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:25 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:25 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:25 volumio volumio[2539]: info: ------------------------------ 30ms Sep 16 16:41:25 volumio volumio[2539]: info: ------------------------------ 26ms Sep 16 16:41:26 volumio volumio[2539]: info: Sep 16 16:41:26 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:26 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:26 volumio volumio[2539]: info: Sep 16 16:41:26 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:26 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:26 volumio volumio[2539]: info: ------------------------------ 3ms Sep 16 16:41:26 volumio volumio[2539]: info: sendMpdCommand status took 3 milliseconds Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:26 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:26 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:26 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:26 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":22,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:26 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:26 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:26 volumio volumio[2539]: info: ------------------------------ 25ms Sep 16 16:41:26 volumio volumio[2539]: info: Sep 16 16:41:26 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:26 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:26 volumio volumio[2539]: info: Sep 16 16:41:26 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:26 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:26 volumio volumio[2539]: info: ------------------------------ 5ms Sep 16 16:41:26 volumio volumio[2539]: info: sendMpdCommand status took 4 milliseconds Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:26 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:26 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:26 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:26 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:26 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Petrece Romaneste cu Radio PetrecaretZu www.radiopetrecaretzu.ro Radio Petrecaretzu Popular Intens Populara Petrecere Manele Etno Dance ZU DEC 22 (38)","artist":"Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:26 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:26 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:26 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:26 volumio volumio[2539]: info: ------------------------------ 21ms Sep 16 16:41:29 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::volumioReplaceandPlayItems Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::ClearQueue Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::stPlaybackTimer Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::serviceStop Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::serviceStop Sep 16 16:41:29 volumio volumio[2539]: info: [1758012089584] ControllerWebradio::stop Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::clearPlayQueue Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::addQueueItems Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::addQueueItems Sep 16 16:41:29 volumio volumio[2539]: info: Preload queue cleared Sep 16 16:41:29 volumio volumio[2539]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400 Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::volumioPushQueue Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::saveQueue Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::updateTrackBlock Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrackBlock Sep 16 16:41:29 volumio volumio[2539]: info: CoreCommandRouter::volumioPlay Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::play index 0 Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::stop Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::play index undefined Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::startPlaybackTimer Sep 16 16:41:29 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:29 volumio volumio[2539]: info: [1758012089600] ControllerWebradio::clearAddPlayTrack Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand stop Sep 16 16:41:29 volumio volumio[2539]: info: Sep 16 16:41:29 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand stop took 28 milliseconds Sep 16 16:41:29 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand stop took 13 milliseconds Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand clear Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:29 volumio volumio[2539]: info: Sep 16 16:41:29 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:29 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand clear took 7 milliseconds Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 7 milliseconds Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:29 volumio volumio[2539]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Sep 16 16:41:29 volumio volumio[2539]: info: ------------------------------ 10ms Sep 16 16:41:29 volumio volumio[2539]: error: updateQueue error: null Sep 16 16:41:29 volumio volumio[2539]: info: ------------------------------ 6ms Sep 16 16:41:29 volumio sudo[3295]: pam_unix(sudo:session): session closed for user root Sep 16 16:41:29 volumio volumio[2539]: info: Sep 16 16:41:29 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:29 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1657400" took 283 milliseconds Sep 16 16:41:29 volumio volumio[2539]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 16 16:41:29 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand play Sep 16 16:41:29 volumio volumio[2539]: info: ------------------------------ 1ms Sep 16 16:41:29 volumio volumio[2539]: info: sendMpdCommand play took 1 milliseconds Sep 16 16:41:30 volumio volumio[2539]: info: Sep 16 16:41:30 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:30 volumio volumio[2539]: info: Sep 16 16:41:30 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand status took 19 milliseconds Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand status took 0 milliseconds Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:30 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:30 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 824/3500) Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:30 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus stop Sep 16 16:41:30 volumio volumio[2539]: info: ------------------------------ 22ms Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:30 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:30 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"(#1 - 824/3500) Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:30 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:30 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:30 volumio volumio[2539]: info: ------------------------------ 18ms Sep 16 16:41:30 volumio volumio[2539]: info: Sep 16 16:41:30 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:30 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:30 volumio volumio[2539]: info: Sep 16 16:41:30 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:30 volumio volumio[2539]: info: ------------------------------ 2ms Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:30 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 16 16:41:30 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:30 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:30 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:30 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":728,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:30 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:30 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:30 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:30 volumio volumio[2539]: info: ------------------------------ 10ms Sep 16 16:41:31 volumio volumio[2539]: info: Sep 16 16:41:31 volumio volumio[2539]: ---------------------------- MPD announces system playlist update Sep 16 16:41:31 volumio volumio[2539]: info: Ignoring MPD Status Update Sep 16 16:41:31 volumio volumio[2539]: info: Sep 16 16:41:31 volumio volumio[2539]: ---------------------------- MPD announces state update: player Sep 16 16:41:31 volumio volumio[2539]: info: ControllerMpd::getState Sep 16 16:41:31 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand status Sep 16 16:41:31 volumio volumio[2539]: info: ------------------------------ 2ms Sep 16 16:41:31 volumio volumio[2539]: info: sendMpdCommand status took 1 milliseconds Sep 16 16:41:31 volumio volumio[2539]: verbose: ControllerMpd::parseState Sep 16 16:41:31 volumio volumio[2539]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 16 16:41:31 volumio volumio[2539]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 16 16:41:31 volumio volumio[2539]: verbose: ControllerMpd::parseTrackInfo Sep 16 16:41:31 volumio volumio[2539]: info: ControllerMpd::pushState Sep 16 16:41:31 volumio volumio[2539]: info: CoreCommandRouter::servicePushState Sep 16 16:41:31 volumio volumio[2539]: info: CorePlayQueue::getTrack 0 Sep 16 16:41:31 volumio volumio[2539]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1001,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Petrece Romaneste cu Radio PetrecaretZu www.radiopetrecaretzu.ro Radio Petrecaretzu Popular Intens Populara Petrecere Manele Etno Dance ZU DEC 22 (38)","artist":"Radio Petrecaretzu wWwRadioPetrecaretzuRo Petrecere Populara Etno Manele Top 40","album":null,"uri":"http://54.39.28.158:8383/stream","trackType":""} Sep 16 16:41:31 volumio volumio[2539]: verbose: CURRENT POSITION 0 Sep 16 16:41:31 volumio volumio[2539]: info: CoreStateMachine::syncState stateService play Sep 16 16:41:31 volumio volumio[2539]: info: CoreStateMachine::syncState currentStatus play Sep 16 16:41:31 volumio volumio[2539]: info: Received an update from plugin. extracting info from payload Sep 16 16:41:31 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:31 volumio volumio[2539]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 16:41:31 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:31 volumio volumio[2539]: info: CoreStateMachine::pushState Sep 16 16:41:31 volumio volumio[2539]: info: CoreCommandRouter::volumioPushState Sep 16 16:41:31 volumio volumio[2539]: info: ------------------------------ 9ms Sep 16 16:41:37 volumio volumio[2539]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:41:37 volumio volumio[2539]: Error: connect ETIMEDOUT 104.244.43.52:80 Sep 16 16:41:37 volumio volumio[2539]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Sep 16 16:41:37 volumio volumio[2539]: errno: -110, Sep 16 16:41:37 volumio volumio[2539]: code: 'ETIMEDOUT', Sep 16 16:41:37 volumio volumio[2539]: syscall: 'connect', Sep 16 16:41:37 volumio volumio[2539]: address: '104.244.43.52', Sep 16 16:41:37 volumio volumio[2539]: port: 80 Sep 16 16:41:37 volumio volumio[2539]: } Sep 16 16:41:37 volumio volumio[2539]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 16:41:37 volumio sudo[3388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 16:40 Sep 16 16:41:37 volumio sudo[3388]: 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="e7445fab547cd22e23d9bef72406cfb610bc1d7d" VOLUMIO_FE_VERSION="5d5e99f8f803b3ec5fc5e8ca13bb4023600f3eee" VOLUMIO_FE3_VERSION="a6ae210575eae1ff43f06ab71e1cd17576840623" VOLUMIO_BE_VERSION="a9e1e1dd6072ffb15c0fcb8938d9ea0d8900c0da" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 24 Apr 2024 02:42:03 PM CEST" VOLUMIO_VERSION="3.661" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="c434411fe8754b648dc7feb1d8f43761"