-- Logs begin at Mon 2025-05-05 15:24:35 CST, end at Mon 2025-05-05 16:17:07 CST. -- May 05 16:16:02 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 16:16:02 volumio-x86 volumio[1189]: info: TuneIn handleBrowseUri: tunein/location May 05 16:16:03 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:03 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:03 volumio-x86 sudo[16759]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 05 16:16:03 volumio-x86 sudo[16759]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 16:16:03 volumio-x86 volumio[1189]: 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: 6 May 05 16:16:03 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:03 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:05 volumio-x86 sudo[16759]: pam_unix(sudo:session): session closed for user root May 05 16:16:07 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 16:16:07 volumio-x86 volumio[1189]: info: TuneIn handleBrowseUri: tunein/best May 05 16:16:08 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 16:16:08 volumio-x86 volumio[1189]: info: TuneIn handleBrowseUri: tunein/best May 05 16:16:10 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 16:16:10 volumio-x86 volumio[1189]: info: TuneIn handleBrowseUri: tunein/popular May 05 16:16:11 volumio-x86 volumio[1189]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 05 16:16:11 volumio-x86 volumio[1189]: info: Retrying Login Due to Network Error May 05 16:16:12 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri May 05 16:16:12 volumio-x86 volumio[1189]: info: Preload queue cleared May 05 16:16:16 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:16 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:16 volumio-x86 sudo[16856]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 05 16:16:16 volumio-x86 sudo[16856]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 16:16:17 volumio-x86 volumio[1189]: 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: 6 May 05 16:16:17 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:17 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:17 volumio-x86 sudo[16856]: pam_unix(sudo:session): session closed for user root May 05 16:16:21 volumio-x86 volumio[1189]: info: MyVolumio login type: Token May 05 16:16:28 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:28 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:28 volumio-x86 sudo[16950]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 05 16:16:28 volumio-x86 sudo[16950]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 16:16:29 volumio-x86 volumio[1189]: 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: 6 May 05 16:16:29 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:29 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:30 volumio-x86 sudo[16950]: pam_unix(sudo:session): session closed for user root May 05 16:16:32 volumio-x86 volumio[1189]: info: Preload queue cleared May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioReplaceandPlayItems May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::ClearQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::stop May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::stPlaybackTimer May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::updateTrackBlock May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrackBlock May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::serviceStop May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 2 May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::serviceStop May 05 16:16:32 volumio-x86 volumio[1189]: info: ControllerMpd::stop May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::clearPlayQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::saveQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::addQueueItems May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::addQueueItems May 05 16:16:32 volumio-x86 volumio[1189]: info: Preload queue cleared May 05 16:16:32 volumio-x86 volumio[1189]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1859817 May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::saveQueue May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::updateTrackBlock May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrackBlock May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPlay May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::play index 0 May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::stop May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::play index undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:32 volumio-x86 volumio[1189]: info: CoreStateMachine::startPlaybackTimer May 05 16:16:32 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:32 volumio-x86 volumio[1189]: info: [1746432992639] ControllerWebradio::clearAddPlayTrack May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop May 05 16:16:32 volumio-x86 volumio[1189]: info: sendMpdCommand stop took 21 milliseconds May 05 16:16:32 volumio-x86 volumio[1189]: info: May 05 16:16:32 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:32 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:32 volumio-x86 volumio[1189]: info: sendMpdCommand stop took 17 milliseconds May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand clear May 05 16:16:32 volumio-x86 volumio[1189]: info: May 05 16:16:32 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:32 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:32 volumio-x86 volumio[1189]: info: sendMpdCommand status took 1 milliseconds May 05 16:16:32 volumio-x86 volumio[1189]: info: sendMpdCommand clear took 0 milliseconds May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1859817" May 05 16:16:32 volumio-x86 volumio[1189]: error: updateQueue error: null May 05 16:16:32 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:32 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 16:16:32 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:32 volumio-x86 volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 05 16:16:32 volumio-x86 volumio[1189]: info: ------------------------------ 2ms May 05 16:16:33 volumio-x86 volumio[1189]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1859817" took 989 milliseconds May 05 16:16:33 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 16:16:33 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand play May 05 16:16:33 volumio-x86 volumio[1189]: info: May 05 16:16:33 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:33 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:33 volumio-x86 volumio[1189]: info: sendMpdCommand play took 1 milliseconds May 05 16:16:33 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:37 volumio-x86 volumio[1189]: info: May 05 16:16:37 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand status took 2 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:37 volumio-x86 volumio[1189]: info: May 05 16:16:37 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:37 volumio-x86 volumio[1189]: 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":"ajazz_64a","artist":"1.FM - Adore Jazz","album":null,"uri":"http://185.33.21.112:80/ajazz_64a","trackType":""} May 05 16:16:37 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus stop May 05 16:16:37 volumio-x86 volumio[1189]: info: ------------------------------ 3ms May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand status took 1 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:37 volumio-x86 volumio[1189]: 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":"ajazz_64a","artist":"1.FM - Adore Jazz","album":null,"uri":"http://185.33.21.112:80/ajazz_64a","trackType":""} May 05 16:16:37 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:16:37 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:37 volumio-x86 volumio[1189]: info: ------------------------------ 4ms May 05 16:16:37 volumio-x86 volumio[1189]: info: May 05 16:16:37 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:37 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:37 volumio-x86 volumio[1189]: info: May 05 16:16:37 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:37 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand status took 0 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:37 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:37 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:37 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:37 volumio-x86 volumio[1189]: 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":"192 Kbps","isStreaming":false,"title":"Helen Shapiro With Humphrey Littelton - I'll Never Smile Again","artist":"1.FM - Adore Jazz Radio","album":null,"uri":"http://185.33.21.112:80/ajazz_64a","trackType":""} May 05 16:16:37 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:16:37 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:37 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:37 volumio-x86 volumio[1189]: info: ------------------------------ 4ms May 05 16:16:41 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:41 volumio-x86 sudo[17079]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 05 16:16:41 volumio-x86 sudo[17079]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 16:16:42 volumio-x86 volumio[1189]: 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: 6 May 05 16:16:42 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:48 volumio-x86 sudo[17079]: pam_unix(sudo:session): session closed for user root May 05 16:16:51 volumio-x86 volumio[1189]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. May 05 16:16:51 volumio-x86 volumio[1189]: info: Retrying Login Due to Network Error May 05 16:16:57 volumio-x86 volumio[1189]: info: Preload queue cleared May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioReplaceandPlayItems May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::ClearQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::stop May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::stPlaybackTimer May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::updateTrackBlock May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrackBlock May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::serviceStop May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::serviceStop May 05 16:16:57 volumio-x86 volumio[1189]: info: [1746433017500] ControllerWebradio::stop May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::clearPlayQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::saveQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::addQueueItems May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::addQueueItems May 05 16:16:57 volumio-x86 volumio[1189]: info: Preload queue cleared May 05 16:16:57 volumio-x86 volumio[1189]: info: Adding Item to queue: http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776 May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::saveQueue May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::updateTrackBlock May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrackBlock May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPlay May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::play index 0 May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::stop May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::play index undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::startPlaybackTimer May 05 16:16:57 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:57 volumio-x86 volumio[1189]: info: [1746433017503] ControllerWebradio::clearAddPlayTrack May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand stop May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand stop took 11 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: info: May 05 16:16:57 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:57 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand stop took 8 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand clear May 05 16:16:57 volumio-x86 volumio[1189]: info: May 05 16:16:57 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:57 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand status took 0 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand clear took 0 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776" May 05 16:16:57 volumio-x86 volumio[1189]: error: updateQueue error: null May 05 16:16:57 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:57 volumio-x86 volumio[1189]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined May 05 16:16:57 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:57 volumio-x86 volumio[1189]: info: May 05 16:16:57 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:57 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1530776" took 219 milliseconds May 05 16:16:57 volumio-x86 volumio[1189]: info: CoreStateMachine::setConsumeUpdateService mpd May 05 16:16:57 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand play May 05 16:16:57 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:16:57 volumio-x86 volumio[1189]: info: sendMpdCommand play took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand status took 1 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: 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":"85.195.88.149:28768","artist":"(#1 - 287/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 05 16:16:58 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus stop May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 4ms May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand status took 1 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: 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":"85.195.88.149:28768","artist":"(#1 - 287/800) Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 05 16:16:58 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:16:58 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 4ms May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:58 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 0ms May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand status took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: 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":"85.195.88.149:28768","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 05 16:16:58 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:16:58 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 5ms May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:16:58 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:16:58 volumio-x86 volumio[1189]: info: May 05 16:16:58 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 0ms May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand status took 0 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:16:58 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 1 milliseconds May 05 16:16:58 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:16:58 volumio-x86 volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1092,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"BONEY M - Gotta go home","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 05 16:16:58 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:16:58 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:16:58 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:16:58 volumio-x86 volumio[1189]: info: ------------------------------ 5ms May 05 16:16:59 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:16:59 volumio-x86 sudo[17185]: root : TTY=unknown ; PWD=/data/plugins/user_interface/peppy_screensaver ; USER=root ; COMMAND=/usr/bin/python3 ./screensaver/volumio_peppymeter.py May 05 16:16:59 volumio-x86 sudo[17185]: pam_unix(sudo:session): session opened for user root by (uid=0) May 05 16:16:59 volumio-x86 volumio[1189]: 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: 6 May 05 16:16:59 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioGetState May 05 16:17:01 volumio-x86 volumio[1189]: info: MyVolumio login type: Token May 05 16:17:03 volumio-x86 sudo[17185]: pam_unix(sudo:session): session closed for user root May 05 16:17:06 volumio-x86 volumio[1189]: info: May 05 16:17:06 volumio-x86 volumio[1189]: ---------------------------- MPD announces system playlist update May 05 16:17:06 volumio-x86 volumio[1189]: info: Ignoring MPD Status Update May 05 16:17:06 volumio-x86 volumio[1189]: info: May 05 16:17:06 volumio-x86 volumio[1189]: ---------------------------- MPD announces state update: player May 05 16:17:06 volumio-x86 volumio[1189]: info: ControllerMpd::getState May 05 16:17:06 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand status May 05 16:17:06 volumio-x86 volumio[1189]: info: ------------------------------ 1ms May 05 16:17:06 volumio-x86 volumio[1189]: info: sendMpdCommand status took 0 milliseconds May 05 16:17:06 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseState May 05 16:17:06 volumio-x86 volumio[1189]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 05 16:17:06 volumio-x86 volumio[1189]: info: sendMpdCommand playlistinfo took 0 milliseconds May 05 16:17:06 volumio-x86 volumio[1189]: verbose: ControllerMpd::parseTrackInfo May 05 16:17:06 volumio-x86 volumio[1189]: info: ControllerMpd::pushState May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreCommandRouter::servicePushState May 05 16:17:06 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:17:06 volumio-x86 volumio[1189]: verbose: STATE SERVICE {"status":"play","position":0,"seek":9080,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"85.195.88.149:28768","artist":"Radio Osttirol","album":null,"uri":"http://85.195.88.149:28768","trackType":"149:28768"} May 05 16:17:06 volumio-x86 volumio[1189]: verbose: CURRENT POSITION 0 May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState stateService play May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreStateMachine::syncState currentStatus play May 05 16:17:06 volumio-x86 volumio[1189]: info: Received an update from plugin. extracting info from payload May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:17:06 volumio-x86 volumio[1189]: info: CorePlayQueue::getTrack 0 May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreStateMachine::pushState May 05 16:17:06 volumio-x86 volumio[1189]: info: CoreCommandRouter::volumioPushState May 05 16:17:06 volumio-x86 volumio[1189]: info: ------------------------------ 6ms May 05 16:17:07 volumio-x86 volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 16:17:07 volumio-x86 volumio[1189]: Error: connect ETIMEDOUT 31.13.67.33:80 May 05 16:17:07 volumio-x86 volumio[1189]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { May 05 16:17:07 volumio-x86 volumio[1189]: errno: -110, May 05 16:17:07 volumio-x86 volumio[1189]: code: 'ETIMEDOUT', May 05 16:17:07 volumio-x86 volumio[1189]: syscall: 'connect', May 05 16:17:07 volumio-x86 volumio[1189]: address: '31.13.67.33', May 05 16:17:07 volumio-x86 volumio[1189]: port: 80 May 05 16:17:07 volumio-x86 volumio[1189]: } May 05 16:17:07 volumio-x86 volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 05 16:17:07 volumio-x86 sudo[17248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-05 16:16 May 05 16:17:07 volumio-x86 sudo[17248]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"