Feb 21 21:42:29 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:29 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:29 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Feb 21 21:42:29 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 21 21:42:29 volumio sudo[4659]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 21 21:42:29 volumio sudo[4659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:29 volumio sudo[4659]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:29 volumio sudo[4670]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 21:42:29 volumio sudo[4664]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 21:42:29 volumio sudo[4670]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:29 volumio sudo[4664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:30 volumio sudo[4670]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:30 volumio sudo[4664]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:30 volumio sudo[4675]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 21 21:42:30 volumio sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:30 volumio sudo[4675]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:30 volumio sudo[4683]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 21:42:30 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:30 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:30 volumio sudo[4686]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 21:42:30 volumio sudo[4686]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:30 volumio sudo[4686]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:30 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:30 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:30 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:30 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 21:42:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 21:42:32 volumio sudo[4706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 21:42:32 volumio sudo[4706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:32 volumio sudo[4706]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:32 volumio sudo[4708]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 21:42:32 volumio sudo[4708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:32 volumio sudo[4708]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:32 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Feb 21 21:42:33 volumio sudo[4712]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 21 21:42:33 volumio sudo[4712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:33 volumio sudo[4712]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:33 volumio sudo[4714]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 21 21:42:33 volumio sudo[4714]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:42:33 volumio sudo[4714]: pam_unix(sudo:session): session closed for user root Feb 21 21:42:33 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248 from 192.168.5.227 UA: Mozilla/5.0 (Linux; Android 16; SM-S908B Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/144.0.7559.132 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::volumioGetVisibleSources Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::volumioGetQueue Feb 21 21:42:33 volumio volumio[1233]: info: CoreStateMachine::getQueue Feb 21 21:42:33 volumio volumio[1233]: info: CorePlayQueue::getQueue Feb 21 21:42:33 volumio volumio[1233]: info: Listing playlists Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 21 21:42:33 volumio volumio[1233]: info: Received Get System Info Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:33 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 21 21:42:33 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 21:42:34 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 21:42:34 volumio volumio[1233]: info: Received Get System Info Feb 21 21:42:34 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:34 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:34 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:34 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:34 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:35 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 21 21:42:36 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 21:42:36 volumio volumio[1233]: info: Received Get System Info Feb 21 21:42:36 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:36 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:36 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:36 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:36 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:37 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::ClearQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::addQueueItems Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::addQueueItems Feb 21 21:42:37 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:37 volumio volumio[1233]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s272597 Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::play index 0 Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::play index undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:37 volumio volumio[1233]: info: [1771710157478] ControllerWebradio::clearAddPlayTrack Feb 21 21:42:37 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:37 volumio volumio[1233]: info: sendMpdCommand stop took 4 milliseconds Feb 21 21:42:37 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 21:42:37 volumio upmpdcli[1592]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Feb 21 21:42:37 volumio volumio[1233]: info: sendMpdCommand clear took 2 milliseconds Feb 21 21:42:37 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "https://s4.citrus3.com:8068/stream" Feb 21 21:42:37 volumio volumio[1233]: info: Feb 21 21:42:37 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:37 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:37 volumio volumio[1233]: info: Feb 21 21:42:37 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:37 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:37 volumio volumio[1233]: info: Feb 21 21:42:37 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:37 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:37 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::ClearQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::addQueueItems Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::addQueueItems Feb 21 21:42:37 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:37 volumio volumio[1233]: info: Adding Item to queue: https://mymasjid.stream/barryic Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:37 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::play index 0 Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::play index undefined Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:37 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:42:37 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:37 volumio volumio[1233]: info: [1771710157654] ControllerWebradio::clearAddPlayTrack Feb 21 21:42:37 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "https://s4.citrus3.com:8068/stream" Feb 21 21:42:39 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:39 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:39 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:39 volumio volumio[1233]: info: ------------------------------ 1795ms Feb 21 21:42:39 volumio volumio[1233]: info: ------------------------------ 1793ms Feb 21 21:42:39 volumio volumio[1233]: info: ------------------------------ 1792ms Feb 21 21:42:39 volumio volumio[1233]: info: sendMpdCommand stop took 1628 milliseconds Feb 21 21:42:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 21:42:39 volumio volumio[1233]: info: Feb 21 21:42:39 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:39 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:39 volumio volumio[1233]: info: Feb 21 21:42:39 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:39 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:39 volumio volumio[1233]: info: sendMpdCommand add "https://s4.citrus3.com:8068/stream" took 5 milliseconds Feb 21 21:42:39 volumio volumio[1233]: info: sendMpdCommand clear took 2 milliseconds Feb 21 21:42:39 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 21:42:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Feb 21 21:42:39 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "https://mymasjid.stream/barryic" Feb 21 21:42:39 volumio volumio[1233]: info: Feb 21 21:42:39 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:39 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:39 volumio volumio[1233]: info: Feb 21 21:42:39 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:39 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:39 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:39 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:39 volumio volumio[1233]: info: ------------------------------ 9ms Feb 21 21:42:39 volumio volumio[1233]: info: ------------------------------ 9ms Feb 21 21:42:39 volumio volumio[1233]: info: sendMpdCommand play took 8 milliseconds Feb 21 21:42:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:39 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:39 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:40 volumio volumio[1233]: verbose: New Socket.io Connection to 192.168.5.248:3000 from 192.168.5.227 UA: Dart/3.10 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Feb 21 21:42:40 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 21 21:42:40 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 21 21:42:40 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "https://mymasjid.stream/barryic" Feb 21 21:42:40 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:40 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:40 volumio volumio[1233]: info: Feb 21 21:42:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:40 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 1091ms Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 1089ms Feb 21 21:42:40 volumio volumio[1233]: info: sendMpdCommand add "https://mymasjid.stream/barryic" took 3 milliseconds Feb 21 21:42:40 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 21:42:40 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Feb 21 21:42:40 volumio volumio[1233]: info: Feb 21 21:42:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:40 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:40 volumio volumio[1233]: info: Feb 21 21:42:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:40 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 5ms Feb 21 21:42:40 volumio volumio[1233]: info: sendMpdCommand play took 5 milliseconds Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 4ms Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 3ms Feb 21 21:42:40 volumio volumio[1233]: info: Feb 21 21:42:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:40 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:40 volumio volumio[1233]: info: Feb 21 21:42:40 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:40 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 3ms Feb 21 21:42:40 volumio volumio[1233]: info: ------------------------------ 2ms Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: info: Feb 21 21:42:41 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 21 milliseconds Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 21 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand clearerror took 16 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 16 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 15 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 13 milliseconds Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 86ms Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand status took 86 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand clearerror took 79 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand clearerror took 79 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 71 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 70 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 59 milliseconds Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 57 milliseconds Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 210ms Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 211ms Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 214ms Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 207ms Feb 21 21:42:41 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 118 milliseconds Feb 21 21:42:41 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:41 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:41 volumio volumio[1233]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"barryic","artist":null,"album":null,"uri":"https://mymasjid.stream/barryic","trackType":""} Feb 21 21:42:41 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState stateService stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: No code Feb 21 21:42:41 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:41 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:41 volumio volumio[1233]: info: ------------------------------ 248ms Feb 21 21:42:42 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:42 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::ClearQueue Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:42 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::addQueueItems Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::addQueueItems Feb 21 21:42:42 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:42 volumio volumio[1233]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s272597 Feb 21 21:42:42 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 21:42:42 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:42 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::play index 0 Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::play index undefined Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:42 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:42:42 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:42 volumio volumio[1233]: info: [1771710162456] ControllerWebradio::clearAddPlayTrack Feb 21 21:42:42 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:42 volumio volumio[1233]: info: sendMpdCommand stop took 5 milliseconds Feb 21 21:42:42 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 21:42:42 volumio volumio[1233]: info: Feb 21 21:42:42 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:42 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:42 volumio volumio[1233]: info: sendMpdCommand clear took 4 milliseconds Feb 21 21:42:42 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "https://s4.citrus3.com:8068/stream" Feb 21 21:42:42 volumio volumio[1233]: info: Feb 21 21:42:42 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:42 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:42 volumio volumio[1233]: info: Feb 21 21:42:42 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:42 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:42 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:42 volumio volumio[1233]: info: ------------------------------ 7ms Feb 21 21:42:43 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 21 21:42:44 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "https://s4.citrus3.com:8068/stream" Feb 21 21:42:44 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:44 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:44 volumio volumio[1233]: info: ------------------------------ 1829ms Feb 21 21:42:44 volumio volumio[1233]: info: ------------------------------ 1829ms Feb 21 21:42:44 volumio volumio[1233]: info: sendMpdCommand add "https://s4.citrus3.com:8068/stream" took 3 milliseconds Feb 21 21:42:44 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 21:42:44 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Feb 21 21:42:44 volumio volumio[1233]: info: Feb 21 21:42:44 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:44 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:44 volumio volumio[1233]: info: Feb 21 21:42:44 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:44 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:44 volumio volumio[1233]: info: Feb 21 21:42:44 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:44 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:44 volumio volumio[1233]: info: sendMpdCommand play took 9 milliseconds Feb 21 21:42:44 volumio volumio[1233]: info: ------------------------------ 8ms Feb 21 21:42:44 volumio volumio[1233]: info: ------------------------------ 6ms Feb 21 21:42:44 volumio volumio[1233]: info: ------------------------------ 5ms Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 6 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 10 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 7 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 7 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 18ms Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 10 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 3 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 81ms Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 79ms Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 81ms Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:46 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 66 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":null,"album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"stream","artist":"no name","album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 124ms Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 118ms Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:46 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:46 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:46 volumio volumio[1233]: info: Feb 21 21:42:46 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 91ms Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 90 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 11ms Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 10 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 9ms Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":968,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"no name","album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 131ms Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 21 21:42:46 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 36 milliseconds Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1095,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"no name","album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1095,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":1,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"stream","artist":"no name","album":null,"uri":"https://s4.citrus3.com:8068/stream","trackType":""} Feb 21 21:42:46 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:46 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:46 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 114ms Feb 21 21:42:46 volumio volumio[1233]: info: ------------------------------ 115ms Feb 21 21:42:48 volumio volumio[1233]: info: VolumeController::SetAlsaVolume41 Feb 21 21:42:48 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:48 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:48 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:49 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::ClearQueue Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::serviceStop Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::serviceStop Feb 21 21:42:49 volumio volumio[1233]: info: [1771710169271] ControllerWebradio::stop Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::clearPlayQueue Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::addQueueItems Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::addQueueItems Feb 21 21:42:49 volumio volumio[1233]: info: Preload queue cleared Feb 21 21:42:49 volumio volumio[1233]: info: Adding Item to queue: https://relay.emasjidlive.uk/almubarakradio Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPushQueue Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::saveQueue Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPlay Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::play index 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::play index undefined Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::startPlaybackTimer Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: [1771710169292] ControllerWebradio::clearAddPlayTrack Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand stop took 53 milliseconds Feb 21 21:42:49 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand stop took 34 milliseconds Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand clear Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:49 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:49 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:49 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand status took 12 milliseconds Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand clear took 10 milliseconds Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand status took 7 milliseconds Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand load "https://relay.emasjidlive.uk/almubarakradio" Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:49 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 21 21:42:49 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:49 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:49 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:49 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 21 21:42:49 volumio volumio[1233]: info: ------------------------------ 48ms Feb 21 21:42:49 volumio volumio[1233]: info: ------------------------------ 48ms Feb 21 21:42:49 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:49 volumio volumio[1233]: info: ------------------------------ 45ms Feb 21 21:42:49 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 21 21:42:49 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:49 volumio volumio[1233]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 21 21:42:49 volumio volumio[1233]: info: ------------------------------ 60ms Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:49 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:49 volumio volumio[1233]: info: Feb 21 21:42:49 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:49 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:49 volumio volumio[1233]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 21 21:42:50 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand add "https://relay.emasjidlive.uk/almubarakradio" Feb 21 21:42:50 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:50 volumio volumio[1233]: error: updateQueue error: null Feb 21 21:42:50 volumio volumio[1233]: info: ------------------------------ 1274ms Feb 21 21:42:50 volumio volumio[1233]: info: ------------------------------ 1273ms Feb 21 21:42:50 volumio volumio[1233]: info: Feb 21 21:42:50 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:50 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:50 volumio volumio[1233]: info: sendMpdCommand add "https://relay.emasjidlive.uk/almubarakradio" took 4 milliseconds Feb 21 21:42:50 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 21 21:42:50 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand play Feb 21 21:42:50 volumio volumio[1233]: info: Feb 21 21:42:50 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:50 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:50 volumio volumio[1233]: info: Feb 21 21:42:50 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:50 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:50 volumio volumio[1233]: info: ------------------------------ 6ms Feb 21 21:42:50 volumio volumio[1233]: info: sendMpdCommand play took 5 milliseconds Feb 21 21:42:50 volumio volumio[1233]: info: ------------------------------ 5ms Feb 21 21:42:50 volumio volumio[1233]: info: ------------------------------ 3ms Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 21 21:42:51 volumio volumio[1233]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Feb 21 21:42:51 volumio volumio[1233]: info: Received Get System Version Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 21:42:51 volumio volumio[1233]: info: Received Get System Info Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:42:51 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:42:51 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 5 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 10 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 8 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 8 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 7 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 5 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus stop Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 19ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 5 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 92ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 91ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 92ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 91ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 79 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: 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":"almubarakradio","artist":null,"album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 109ms Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 8ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 7 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 6ms Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces system playlist update Feb 21 21:42:52 volumio volumio[1233]: info: Ignoring MPD Status Update Feb 21 21:42:52 volumio volumio[1233]: info: Feb 21 21:42:52 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 19ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 17 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 64ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 55 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 55ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 53 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 52ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 50 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 49ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand status took 46 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 42 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 129ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 129ms Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 96 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 96 milliseconds Feb 21 21:42:52 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 96 milliseconds Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:52 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:52 volumio volumio[1233]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Quran - Complete Quran With Authentic Urdu Translation Par","artist":"Al Mubarak Radio","album":null,"uri":"https://relay.emasjidlive.uk/almubarakradio","trackType":""} Feb 21 21:42:52 volumio volumio[1233]: verbose: CURRENT POSITION 0 Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState stateService play Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::syncState currentStatus play Feb 21 21:42:52 volumio volumio[1233]: info: Received an update from plugin. extracting info from payload Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:52 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 200ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 198ms Feb 21 21:42:52 volumio volumio[1233]: info: ------------------------------ 197ms Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::volumioStop Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::stop Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::stPlaybackTimer Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::updateTrackBlock Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrackBlock Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::serviceStop Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::serviceStop Feb 21 21:42:55 volumio volumio[1233]: info: [1771710175270] ControllerWebradio::stop Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand stop Feb 21 21:42:55 volumio volumio[1233]: info: Feb 21 21:42:55 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand stop took 47 milliseconds Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:55 volumio volumio[1233]: info: Feb 21 21:42:55 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:55 volumio volumio[1233]: info: Feb 21 21:42:55 volumio volumio[1233]: ---------------------------- MPD announces state update: player Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::getState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand status Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand status took 12 milliseconds Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand status took 9 milliseconds Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand status took 8 milliseconds Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 3 milliseconds Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 2 milliseconds Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseState Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 21 21:42:55 volumio volumio[1233]: info: ------------------------------ 48ms Feb 21 21:42:55 volumio volumio[1233]: info: ------------------------------ 46ms Feb 21 21:42:55 volumio volumio[1233]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 21 21:42:55 volumio volumio[1233]: info: sendMpdCommand playlistinfo took 34 milliseconds Feb 21 21:42:55 volumio volumio[1233]: verbose: ControllerMpd::parseTrackInfo Feb 21 21:42:55 volumio volumio[1233]: info: ControllerMpd::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::servicePushState Feb 21 21:42:55 volumio volumio[1233]: info: CoreStateMachine::pushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 21 21:42:55 volumio volumio[1233]: info: CoreCommandRouter::volumioPushState Feb 21 21:42:55 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:42:55 volumio volumio[1233]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Feb 21 21:42:55 volumio volumio[1233]: info: ------------------------------ 68ms Feb 21 21:42:57 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 21:42:57 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 21 21:43:02 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 21:43:02 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 21 21:43:02 volumio volumio[1233]: info: Discovery: Getting this device information Feb 21 21:43:02 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:43:02 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:43:02 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 21 21:43:11 volumio volumio[1233]: info: CALLMETHOD: user_interface now_playing configureVolumioKiosk [object Object] Feb 21 21:43:11 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: now_playing , configureVolumioKiosk Feb 21 21:43:11 volumio volumio[1233]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Feb 21 21:43:12 volumio sudo[4805]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Feb 21 21:43:12 volumio sudo[4805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:12 volumio sudo[4805]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:12 volumio volumio[1233]: [sudo] password for volumio: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Feb 21 21:43:12 volumio sudo[4808]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Feb 21 21:43:12 volumio sudo[4808]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:12 volumio sudo[4808]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:12 volumio volumio[1233]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Feb 21 21:43:12 volumio sudo[4813]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Feb 21 21:43:12 volumio sudo[4813]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:13 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Feb 21 21:43:13 volumio startx[1809]: xinit: connection to X server lost Feb 21 21:43:13 volumio startx[1809]: Feb 21 21:43:14 volumio startx[1809]: waiting for X server to shut down Feb 21 21:43:14 volumio startx[1810]: (II) Server terminated successfully (0). Closing log file. Feb 21 21:43:15 volumio startx[1809]: . Feb 21 21:43:15 volumio startx[1809]: xinit: unexpected signal 15 Feb 21 21:43:15 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Feb 21 21:43:15 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Feb 21 21:43:15 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Feb 21 21:43:15 volumio systemd[1]: volumio-kiosk.service: Consumed 9min 34.691s CPU time. Feb 21 21:43:15 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Feb 21 21:43:15 volumio sudo[4813]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:15 volumio volumio[1233]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 21 21:43:15 volumio startx[4842]: X.Org X Server 1.21.1.7 Feb 21 21:43:15 volumio startx[4842]: X Protocol Version 11, Revision 0 Feb 21 21:43:15 volumio startx[4842]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Feb 21 21:43:15 volumio startx[4842]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=b0b466fc-4556-43af-9f97-ebe56fe4abe4 imgfile=/volumio_current.sqsh bootpart=UUID=0428-C7E4 datapart=UUID=82051e0e-a859-4f54-aa9f-0cc23a932af8 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no fbcon=rotate:0 Feb 21 21:43:15 volumio startx[4842]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Feb 21 21:43:15 volumio startx[4842]: Current version of pixman: 0.44.0 Feb 21 21:43:15 volumio startx[4842]: Before reporting problems, check http://wiki.x.org Feb 21 21:43:15 volumio startx[4842]: to make sure that you have the latest version. Feb 21 21:43:15 volumio startx[4842]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 21 21:43:15 volumio startx[4842]: (++) from command line, (!!) notice, (II) informational, Feb 21 21:43:15 volumio startx[4842]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 21 21:43:15 volumio startx[4842]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Feb 21 21:43:15 2026 Feb 21 21:43:15 volumio startx[4842]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 21 21:43:15 volumio startx[4842]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 21 21:43:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 21 21:43:20 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 21 21:43:26 volumio sudo[4979]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 21 21:43:26 volumio sudo[4979]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:26 volumio sudo[4981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Feb 21 21:43:26 volumio sudo[4981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:26 volumio sudo[4979]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:26 volumio sudo[4983]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Feb 21 21:43:26 volumio sudo[4983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:26 volumio volumio[1233]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 21 21:43:26 volumio sudo[4986]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Feb 21 21:43:26 volumio sudo[4986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:26 volumio systemd[1]: Reloading. Feb 21 21:43:26 volumio sudo[5001]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:4004/localhost:3000/g /opt/volumiokiosk.sh Feb 21 21:43:26 volumio sudo[5001]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:26 volumio sudo[5001]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:27 volumio volumio[1233]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Feb 21 21:43:27 volumio sudo[5005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Feb 21 21:43:27 volumio sudo[5005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:27 volumio volumio[1233]: info: pi_screen_setup: Stopping plugin Feb 21 21:43:27 volumio volumio[1233]: info: pi_screen_setup: Management server stopped Feb 21 21:43:27 volumio volumio[1233]: info: Disabling plugin pi_screen_setup Feb 21 21:43:27 volumio volumio[1233]: info: Done. Feb 21 21:43:28 volumio volumio[1233]: info: Enabling plugin now_playing Feb 21 21:43:28 volumio volumio[1233]: info: Loading plugin "now_playing"... Feb 21 21:43:28 volumio volumio[1233]: info: PLUGIN START: now_playing Feb 21 21:43:28 volumio volumio[1233]: info: [now-playing] ConfigUpdater: config is up to date. Feb 21 21:43:29 volumio volumio[1233]: info: [now-playing] App is listening on port 4004. Feb 21 21:43:29 volumio volumio[1233]: info: [now-playing] Backing up /opt/volumiokiosk.sh to /home/volumio/.now_playing/volumiokiosk.sh.bak Feb 21 21:43:29 volumio sudo[5060]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sed -i s/localhost:3000/localhost:4004/g /opt/volumiokiosk.sh Feb 21 21:43:29 volumio sudo[5060]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:29 volumio sudo[5060]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:29 volumio volumio[1233]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl status volumio-kiosk Feb 21 21:43:29 volumio sudo[5063]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl status volumio-kiosk Feb 21 21:43:29 volumio sudo[5063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio volumio[1233]: info: Enabling plugin touch_display Feb 21 21:43:30 volumio volumio[1233]: info: Loading plugin "touch_display"... Feb 21 21:43:30 volumio volumio[1233]: info: Applying required configuration parameters for plugin touch_display Feb 21 21:43:30 volumio volumio[1233]: info: PLUGIN START: touch_display Feb 21 21:43:30 volumio volumio[1233]: info: Loading i18n strings for locale en Feb 21 21:43:30 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 21 21:43:30 volumio sudo[5069]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Feb 21 21:43:30 volumio sudo[5069]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: No backlight interface detected. Feb 21 21:43:30 volumio sudo[5071]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Feb 21 21:43:30 volumio sudo[5071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio sudo[5073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Feb 21 21:43:30 volumio sudo[5073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio sudo[5069]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Feb 21 21:43:30 volumio sudo[5076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 21 21:43:30 volumio sudo[5076]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio sudo[5080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Feb 21 21:43:30 volumio sudo[5080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio sudo[5080]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Feb 21 21:43:30 volumio sudo[5083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Feb 21 21:43:30 volumio sudo[5083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio sudo[5086]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Feb 21 21:43:30 volumio sudo[5083]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:30 volumio sudo[5086]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Feb 21 21:43:30 volumio sudo[5086]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: IframeKeyboardBridge extension installed successfully Feb 21 21:43:30 volumio sudo[4983]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:30 volumio volumio[1233]: info: touch_display: systemctl enable getty@tty1.service succeeded. Feb 21 21:43:31 volumio sudo[5005]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:31 volumio volumio[1233]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Feb 21 21:43:31 volumio systemd[1]: Reloading. Feb 21 21:43:31 volumio sudo[5098]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Feb 21 21:43:31 volumio sudo[5098]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:34 volumio sudo[5073]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:34 volumio volumio[1233]: info: touch_display: systemctl disable getty@tty1.service succeeded. Feb 21 21:43:34 volumio sudo[4981]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:34 volumio sudo[5063]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:34 volumio volumio[1233]: error: touch_display: Failed to start getty@tty1.service: Error: Command failed: /usr/bin/sudo /bin/systemctl start getty@tty1.service Feb 21 21:43:34 volumio volumio[1233]: Job for getty@tty1.service canceled. Feb 21 21:43:34 volumio volumio[1233]: info: [now-playing] Executing /usr/bin/sudo /bin/systemctl restart volumio-kiosk Feb 21 21:43:34 volumio systemd[1]: Reloading. Feb 21 21:43:34 volumio sudo[5139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk Feb 21 21:43:35 volumio sudo[5139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:36 volumio volumio[1233]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Feb 21 21:43:36 volumio volumio[1233]: info: [now-playing] Weather not configured: Weather is not set up: add Geographic Coordinates in Localization and an OpenWeatherMap API key in Weather Service. Feb 21 21:43:39 volumio volumio[1233]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Feb 21 21:43:39 volumio volumio[1233]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Feb 21 21:43:39 volumio sudo[5076]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:39 volumio sudo[4986]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:39 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Feb 21 21:43:39 volumio startx[4841]: xinit: connection to X server lost Feb 21 21:43:39 volumio startx[4841]: Feb 21 21:43:39 volumio systemd[1]: Stopping volumio-kiosk.service - Volumio Kiosk... Feb 21 21:43:39 volumio sudo[5071]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:39 volumio startx[4841]: waiting for X server to shut down Feb 21 21:43:39 volumio startx[4842]: (II) Server terminated successfully (0). Closing log file. Feb 21 21:43:39 volumio volumio[1233]: info: touch_display: systemctl stop getty@tty1.service succeeded. Feb 21 21:43:39 volumio volumio[1233]: error: touch_display: Failed to stop volumio-kiosk.service: Error: Command failed: /usr/bin/sudo /bin/systemctl stop volumio-kiosk.service Feb 21 21:43:39 volumio volumio[1233]: Job for volumio-kiosk.service canceled. Feb 21 21:43:39 volumio volumio[1233]: info: Disabling plugin touch_display Feb 21 21:43:39 volumio volumio[1233]: info: Done. Feb 21 21:43:39 volumio volumio[1233]: info: touch_display: systemctl daemon-reload succeeded. Feb 21 21:43:40 volumio sudo[5163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 21 21:43:40 volumio sudo[5163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 21 21:43:40 volumio startx[4841]: xinit: unexpected signal 15 Feb 21 21:43:40 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Feb 21 21:43:40 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Feb 21 21:43:40 volumio systemd[1]: Stopped volumio-kiosk.service - Volumio Kiosk. Feb 21 21:43:40 volumio systemd[1]: volumio-kiosk.service: Consumed 24.175s CPU time. Feb 21 21:43:40 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Feb 21 21:43:40 volumio sudo[5139]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:40 volumio sudo[5098]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:40 volumio volumio[1233]: warn: [now-playing] MyBackgroundMonitor stopped Feb 21 21:43:40 volumio volumio[1233]: info: Disabling plugin now_playing Feb 21 21:43:40 volumio volumio[1233]: info: Done. Feb 21 21:43:40 volumio sudo[5163]: pam_unix(sudo:session): session closed for user root Feb 21 21:43:40 volumio volumio[1233]: info: Error: TypeError: Cannot read properties of null (reading 'logger') Feb 21 21:43:40 volumio volumio[1233]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Feb 21 21:43:40 volumio volumio[1233]: info: touch_display: Volumio Kiosk started. Feb 21 21:43:40 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:43:40 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:43:40 volumio volumio[1233]: info: Done. Feb 21 21:43:40 volumio volumio[1233]: info: CoreCommandRouter::volumioGetState Feb 21 21:43:40 volumio volumio[1233]: info: CorePlayQueue::getTrack 0 Feb 21 21:43:41 volumio volumio[1233]: info: touch_display: X display number found: Feb 21 21:43:41 volumio volumio[1233]: info: touch_display: X display number found: 0 Feb 21 21:43:41 volumio startx[5194]: X.Org X Server 1.21.1.7 Feb 21 21:43:41 volumio startx[5194]: X Protocol Version 11, Revision 0 Feb 21 21:43:41 volumio startx[5194]: Current Operating System: Linux volumio 6.12.47-v7+ #1904 SMP Mon Sep 15 13:05:52 BST 2025 armv7l Feb 21 21:43:41 volumio startx[5194]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=b0b466fc-4556-43af-9f97-ebe56fe4abe4 imgfile=/volumio_current.sqsh bootpart=UUID=0428-C7E4 datapart=UUID=82051e0e-a859-4f54-aa9f-0cc23a932af8 uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no fbcon=rotate:0 Feb 21 21:43:41 volumio startx[5194]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Feb 21 21:43:41 volumio startx[5194]: Current version of pixman: 0.44.0 Feb 21 21:43:41 volumio startx[5194]: Before reporting problems, check http://wiki.x.org Feb 21 21:43:41 volumio startx[5194]: to make sure that you have the latest version. Feb 21 21:43:41 volumio startx[5194]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 21 21:43:41 volumio startx[5194]: (++) from command line, (!!) notice, (II) informational, Feb 21 21:43:41 volumio startx[5194]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 21 21:43:41 volumio startx[5194]: (==) Log file: "/var/log/Xorg.0.log", Time: Sat Feb 21 21:43:41 2026 Feb 21 21:43:41 volumio startx[5194]: (==) Using config directory: "/etc/X11/xorg.conf.d" Feb 21 21:43:41 volumio startx[5194]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 21 21:43:41 volumio volumio[1233]: info: touch_display: X display number found: 0 Feb 21 21:43:41 volumio volumio[1233]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Feb 21 21:43:41 volumio volumio[1233]: info: touch_display: X display number found: 0 Feb 21 21:43:44 volumio volumio[1233]: info: touch_display: Setting screensaver timeout to 120 seconds. Feb 21 21:43:45 volumio volumio[1233]: info: Disabling plugin touch_display Feb 21 21:43:45 volumio volumio[1233]: info: Done. Feb 21 21:43:46 volumio volumio[1233]: info: Disabling plugin touch_display Feb 21 21:43:46 volumio volumio[1233]: info: Done. Feb 21 21:43:47 volumio volumio[1233]: info: Disabling plugin now_playing Feb 21 21:43:47 volumio volumio[1233]: info: Done. Feb 21 21:43:47 volumio volumio[1233]: info: Disabling plugin touch_display Feb 21 21:43:47 volumio volumio[1233]: info: Done. Feb 21 21:43:48 volumio volumio[1233]: info: Disabling plugin touch_display Feb 21 21:43:48 volumio volumio[1233]: info: Done. Feb 21 21:43:49 volumio volumio[1233]: info: Disabling plugin now_playing Feb 21 21:43:49 volumio volumio[1233]: info: Done. Feb 21 21:43:49 volumio volumio[1233]: info: Disabling plugin now_playing Feb 21 21:43:49 volumio volumio[1233]: info: Done. Feb 21 21:43:51 volumio volumio[1233]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:43:51 volumio volumio[1233]: TypeError: Cannot read properties of null (reading 'logger') Feb 21 21:43:51 volumio volumio[1233]: at NowPlayingContext.getLogger (/data/plugins/user_interface/now_playing/dist/lib/NowPlayingContext.js:72:83) Feb 21 21:43:51 volumio volumio[1233]: at stdLogError (/data/plugins/user_interface/now_playing/dist/app/Router.js:44:33) Feb 21 21:43:51 volumio volumio[1233]: at /data/plugins/user_interface/now_playing/dist/app/Router.js:47:46 Feb 21 21:43:51 volumio volumio[1233]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Feb 21 21:43:51 volumio volumio[1233]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 21 21:43:54 volumio sudo[5413]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-21 21:42' Feb 21 21:43:54 volumio sudo[5413]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"