Jan 25 13:30:00 volumio volumio[944]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 25 13:30:01 volumio volumio[944]: info: MyVolumio token set successfully Jan 25 13:30:01 volumio volumio[944]: info: MYVOLUMIO: Adding device Jan 25 13:30:01 volumio volumio[944]: info: MYVOLUMIO: Evaluating Server Jan 25 13:30:01 volumio volumio[944]: info: MyVolumio status changed Jan 25 13:30:01 volumio volumio[944]: info: Streaming services startup Jan 25 13:30:01 volumio volumio[944]: info: Starting Streaming Daemon Jan 25 13:30:01 volumio volumio[944]: info: Removing browser output: myVolumio user plan is not superstar Jan 25 13:30:01 volumio volumio[944]: info: Removing audio output: Jan 25 13:30:01 volumio volumio[944]: info: Stoppping Tunnel 1 Jan 25 13:30:01 volumio sudo[5518]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 13:30:01 volumio sudo[5518]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:30:01 volumio sudo[5520]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Jan 25 13:30:01 volumio sudo[5520]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:30:01 volumio sudo[5518]: pam_unix(sudo:session): session closed for user root Jan 25 13:30:01 volumio volumio[944]: error: Cannot start Volumio Streaming Daemon Jan 25 13:30:01 volumio volumio[944]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 25 13:30:01 volumio volumio[944]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio volumio[944]: info: Setting Geolocation for MyVolumio to eu11 Jan 25 13:30:01 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:01 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:01 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 25 13:30:01 volumio sudo[5520]: pam_unix(sudo:session): session closed for user root Jan 25 13:30:02 volumio volumio[944]: info: Remote SSH Stopped Jan 25 13:30:02 volumio volumio[944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 25 13:30:02 volumio volumio[944]: info: Updating MyVolumio device info Jan 25 13:30:02 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:02 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:02 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:03 volumio volumio[944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 25 13:30:06 volumio volumio[944]: info: MYVOLUMIO: Adding device Jan 25 13:30:06 volumio volumio[944]: info: MYVOLUMIO: Evaluating Server Jan 25 13:30:07 volumio volumio[944]: info: Setting Geolocation for MyVolumio to eu7 Jan 25 13:30:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:07 volumio volumio[944]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 25 13:30:08 volumio volumio[944]: info: Updating MyVolumio device info Jan 25 13:30:08 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:08 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:08 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 13:30:08 volumio volumio[944]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 25 13:30:11 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 25 13:30:11 volumio volumio[944]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 25 13:30:11 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Jan 25 13:30:11 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::volumioGetBrowseSources Jan 25 13:30:16 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 13:30:20 volumio upmpdcli[5570]: writing RSA key Jan 25 13:30:20 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:20 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:20 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:20 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:20 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:20 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:20 volumio volumio[944]: info: [1769344220897] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:20 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:20 volumio volumio[944]: info: sendMpdCommand stop took 5 milliseconds Jan 25 13:30:20 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:20 volumio volumio[944]: info: Jan 25 13:30:20 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:20 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:20 volumio volumio[944]: info: sendMpdCommand clear took 9 milliseconds Jan 25 13:30:20 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:20 volumio volumio[944]: info: Jan 25 13:30:20 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:20 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:20 volumio volumio[944]: info: Jan 25 13:30:20 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:20 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:20 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:20 volumio volumio[944]: info: ------------------------------ 27ms Jan 25 13:30:23 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:23 volumio volumio[944]: info: [1769344223551] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:23 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:23 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:23 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:23 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:23 volumio volumio[944]: info: [1769344223825] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:23 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:24 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:24 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:24 volumio volumio[944]: info: ------------------------------ 3104ms Jan 25 13:30:24 volumio volumio[944]: info: ------------------------------ 3099ms Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand stop took 474 milliseconds Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand stop took 200 milliseconds Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:24 volumio volumio[944]: info: Jan 25 13:30:24 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:24 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:24 volumio volumio[944]: info: Jan 25 13:30:24 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:24 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:24 volumio volumio[944]: info: Jan 25 13:30:24 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:24 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 16 milliseconds Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand clear took 15 milliseconds Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand clear took 16 milliseconds Jan 25 13:30:24 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:24 volumio volumio[944]: info: Jan 25 13:30:24 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:24 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:24 volumio volumio[944]: info: Jan 25 13:30:24 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:24 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:24 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:24 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:24 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:24 volumio volumio[944]: info: ------------------------------ 33ms Jan 25 13:30:24 volumio volumio[944]: info: ------------------------------ 30ms Jan 25 13:30:24 volumio volumio[944]: info: ------------------------------ 30ms Jan 25 13:30:24 volumio volumio[944]: info: sendMpdCommand play took 20 milliseconds Jan 25 13:30:24 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:24 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:24 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:24 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:24 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:24 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:24 volumio volumio[944]: info: [1769344224833] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:24 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:25 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: [1769344225070] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:25 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:25 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: [1769344225273] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:25 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:25 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:25 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:25 volumio volumio[944]: info: [1769344225489] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:25 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:25 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: info: CoreCommandRouter::volumioGetQueue Jan 25 13:30:28 volumio volumio[944]: info: CoreStateMachine::getQueue Jan 25 13:30:28 volumio volumio[944]: info: CorePlayQueue::getQueue Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 4335ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 4332ms Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand stop took 3560 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand stop took 3322 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand stop took 3120 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand stop took 2905 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 2401 milliseconds Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:28 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 53 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 56ms Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand clear took 49 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand clear took 48 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand clear took 48 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand clear took 49 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand play took 49 milliseconds Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 46ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 44ms Jan 25 13:30:28 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: info: Jan 25 13:30:28 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:28 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 62ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 58ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 57ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 55ms Jan 25 13:30:28 volumio volumio[944]: info: ------------------------------ 55ms Jan 25 13:30:28 volumio volumio[944]: info: sendMpdCommand play took 45 milliseconds Jan 25 13:30:29 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 25 13:30:29 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 13:30:29 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:30 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:34 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Jan 25 13:30:34 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:35 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:35 volumio volumio[944]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 13:30:35 volumio volumio[944]: info: CoreStateMachine::ClearQueue Jan 25 13:30:35 volumio volumio[944]: info: CoreStateMachine::stop Jan 25 13:30:35 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:35 volumio volumio[944]: info: CorePlayQueue::clearPlayQueue Jan 25 13:30:35 volumio volumio[944]: info: CorePlayQueue::saveQueue Jan 25 13:30:35 volumio volumio[944]: info: CoreCommandRouter::volumioPushQueue Jan 25 13:30:35 volumio volumio[944]: info: CoreStateMachine::addQueueItems Jan 25 13:30:35 volumio volumio[944]: info: CorePlayQueue::addQueueItems Jan 25 13:30:35 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:35 volumio volumio[944]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s44284 Jan 25 13:30:35 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 25 13:30:36 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:36 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 7908ms Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 7906ms Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 6369 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushQueue Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::saveQueue Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::updateTrackBlock Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrackBlock Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::play index 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: info: [1769344236436] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 86 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 86 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 86 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 81ms Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand play took 76 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 73ms Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 69ms Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand stop took 46 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 41ms Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand play took 29 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand play took 29 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand play took 29 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 27ms Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 25ms Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand status took 21 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand clear took 17 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand status took 15 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand status took 11 milliseconds Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 13:30:36 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: No code Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 13:30:36 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: No code Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 264ms Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 262ms Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:36 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:36 volumio volumio[944]: info: Jan 25 13:30:36 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:36 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 282ms Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand status took 276 milliseconds Jan 25 13:30:36 volumio volumio[944]: info: sendMpdCommand playlistinfo took 271 milliseconds Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:36 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Jan 25 13:30:36 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState stateService stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: info: No code Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:36 volumio volumio[944]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 406ms Jan 25 13:30:36 volumio volumio[944]: info: ------------------------------ 431ms Jan 25 13:30:36 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::ClearQueue Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::clearPlayQueue Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::saveQueue Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushQueue Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::addQueueItems Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::addQueueItems Jan 25 13:30:36 volumio volumio[944]: info: Preload queue cleared Jan 25 13:30:36 volumio volumio[944]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s44284 Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPushQueue Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::saveQueue Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::updateTrackBlock Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrackBlock Jan 25 13:30:36 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::play index 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::stop Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:36 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:36 volumio volumio[944]: info: [1769344236973] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:36 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:38 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:38 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 1268ms Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand status took 1264 milliseconds Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 1263ms Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand status took 1260 milliseconds Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand stop took 1059 milliseconds Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:38 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:38 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:38 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:38 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:38 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:38 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:38 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:38 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 25 13:30:38 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:38 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:38 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:38 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:38 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:38 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:38 volumio volumio[944]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 1336ms Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 1331ms Jan 25 13:30:38 volumio volumio[944]: info: Jan 25 13:30:38 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:38 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:38 volumio volumio[944]: info: Jan 25 13:30:38 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:38 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 83 milliseconds Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand clear took 76 milliseconds Jan 25 13:30:38 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:38 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:38 volumio volumio[944]: info: Jan 25 13:30:38 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:38 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:38 volumio volumio[944]: info: Jan 25 13:30:38 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:38 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:38 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:38 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 75ms Jan 25 13:30:38 volumio volumio[944]: info: ------------------------------ 74ms Jan 25 13:30:38 volumio volumio[944]: info: sendMpdCommand play took 70 milliseconds Jan 25 13:30:40 volumio volumio[944]: info: CoreCommandRouter::volumioPlay Jan 25 13:30:40 volumio volumio[944]: info: CoreStateMachine::play index undefined Jan 25 13:30:40 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 25 13:30:40 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:40 volumio volumio[944]: info: CoreStateMachine::startPlaybackTimer Jan 25 13:30:40 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:40 volumio volumio[944]: info: [1769344240193] ControllerWebradio::clearAddPlayTrack Jan 25 13:30:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand stop Jan 25 13:30:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:40 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:40 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 2220ms Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 2217ms Jan 25 13:30:40 volumio volumio[944]: info: sendMpdCommand stop took 144 milliseconds Jan 25 13:30:40 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 6 milliseconds Jan 25 13:30:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand clear Jan 25 13:30:40 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:40 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 16ms Jan 25 13:30:40 volumio volumio[944]: info: sendMpdCommand clear took 12 milliseconds Jan 25 13:30:40 volumio volumio[944]: info: sendMpdCommand play took 13 milliseconds Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 10ms Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 9ms Jan 25 13:30:40 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand load "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: info: Jan 25 13:30:40 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:40 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:40 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:40 volumio volumio[944]: info: ------------------------------ 13ms Jan 25 13:30:42 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" Jan 25 13:30:42 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:42 volumio volumio[944]: error: updateQueue error: null Jan 25 13:30:42 volumio volumio[944]: info: Jan 25 13:30:42 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:42 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:42 volumio volumio[944]: info: ------------------------------ 2011ms Jan 25 13:30:42 volumio volumio[944]: info: ------------------------------ 2009ms Jan 25 13:30:42 volumio volumio[944]: info: sendMpdCommand add "https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein" took 6 milliseconds Jan 25 13:30:42 volumio volumio[944]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 25 13:30:42 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand play Jan 25 13:30:42 volumio volumio[944]: info: Jan 25 13:30:42 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:42 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:42 volumio volumio[944]: info: Jan 25 13:30:42 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:42 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:42 volumio volumio[944]: info: ------------------------------ 13ms Jan 25 13:30:42 volumio volumio[944]: info: sendMpdCommand play took 9 milliseconds Jan 25 13:30:42 volumio volumio[944]: info: ------------------------------ 8ms Jan 25 13:30:42 volumio volumio[944]: info: ------------------------------ 5ms Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 55 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 62 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 60 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 11 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 8 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus stop Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 86ms Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 23 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 5 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 284ms Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 282ms Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 230ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 200 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 197 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"high?aggregator=tunein","artist":null,"album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 352ms Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 332ms Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 24ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 21 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 19ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 15 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 14ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 12 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:44 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:44 volumio volumio[944]: info: Jan 25 13:30:44 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 33ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 29 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 21 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 19 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand playlistinfo took 18 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 16ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 12 milliseconds Jan 25 13:30:44 volumio volumio[944]: info: ------------------------------ 12ms Jan 25 13:30:44 volumio volumio[944]: info: sendMpdCommand status took 10 milliseconds Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:44 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:44 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:44 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:44 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:44 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:44 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:44 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 231ms Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 225ms Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 228ms Jan 25 13:30:45 volumio volumio[944]: info: sendMpdCommand playlistinfo took 188 milliseconds Jan 25 13:30:45 volumio volumio[944]: info: sendMpdCommand playlistinfo took 186 milliseconds Jan 25 13:30:45 volumio volumio[944]: info: sendMpdCommand playlistinfo took 186 milliseconds Jan 25 13:30:45 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:45 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:45 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:45 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:45 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:45 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:45 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:45 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:45 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:45 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:45 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:45 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:45 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:45 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":984,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"von Frédéric Chopin","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:45 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:45 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:45 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 405ms Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 385ms Jan 25 13:30:45 volumio volumio[944]: info: ------------------------------ 383ms Jan 25 13:30:50 volumio volumio[944]: info: VolumeController::SetAlsaVolume72 Jan 25 13:30:50 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:50 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:50 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:50 volumio volumio[944]: info: VolumeController::SetAlsaVolume63 Jan 25 13:30:50 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:50 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:50 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:51 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:51 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:30:51 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:30:51 volumio volumio[944]: info: Jan 25 13:30:51 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 19ms Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand status took 17 milliseconds Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 13ms Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand status took 9 milliseconds Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 17ms Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand status took 14 milliseconds Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand playlistinfo took 9 milliseconds Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand playlistinfo took 8 milliseconds Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:51 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:51 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mit: Jeneba Kanneh-Mason","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:51 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:51 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:51 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:51 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mit: Jeneba Kanneh-Mason","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:51 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:51 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 177ms Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 171ms Jan 25 13:30:51 volumio volumio[944]: info: sendMpdCommand playlistinfo took 147 milliseconds Jan 25 13:30:51 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:30:51 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:30:51 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:30:51 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":7486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"mit: Jeneba Kanneh-Mason","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:30:51 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:30:51 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: ------------------------------ 231ms Jan 25 13:30:51 volumio volumio[944]: info: VolumeController::SetAlsaVolume56 Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:51 volumio volumio[944]: info: VolumeController::SetAlsaVolume52 Jan 25 13:30:51 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:51 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:30:52 volumio volumio[944]: info: VolumeController::SetAlsaVolume67 Jan 25 13:30:52 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:30:52 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:30:52 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:00 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 25 13:31:03 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 13:31:03 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 13:31:03 volumio systemd[1]: setdatetime-helper.service: Consumed 1.821s CPU time. Jan 25 13:31:05 volumio volumio[944]: info: CoreCommandRouter::volumioGetState Jan 25 13:31:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 25 13:31:07 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:31:11 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:31:11 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces system playlist update Jan 25 13:31:11 volumio volumio[944]: info: Ignoring MPD Status Update Jan 25 13:31:11 volumio volumio[944]: info: Jan 25 13:31:11 volumio volumio[944]: ---------------------------- MPD announces state update: player Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::getState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand status Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 13ms Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand status took 11 milliseconds Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 10ms Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand status took 13 milliseconds Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 13ms Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand status took 10 milliseconds Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseState Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand playlistinfo took 13 milliseconds Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 25 13:31:11 volumio volumio[944]: info: sendMpdCommand playlistinfo took 6 milliseconds Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:31:11 volumio volumio[944]: verbose: ControllerMpd::parseTrackInfo Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:31:11 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:31:11 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Klavierkonzert Nr. 2 f-Moll, op. 21","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:31:11 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:31:11 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:31:11 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:31:11 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Klavierkonzert Nr. 2 f-Moll, op. 21","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:31:11 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:31:11 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: ControllerMpd::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::servicePushState Jan 25 13:31:11 volumio volumio[944]: info: CorePlayQueue::getTrack 0 Jan 25 13:31:11 volumio volumio[944]: verbose: STATE SERVICE {"status":"play","position":0,"seek":27486,"duration":0,"samplerate":"48 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"256 Kbps","isStreaming":false,"title":"Klavierkonzert Nr. 2 f-Moll, op. 21","artist":"BR-KLASSIK","album":null,"uri":"https://dispatcher.rndfnk.com/br/brklassik/live/mp3/high?aggregator=tunein","trackType":""} Jan 25 13:31:11 volumio volumio[944]: verbose: CURRENT POSITION 0 Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState stateService play Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::syncState currentStatus play Jan 25 13:31:11 volumio volumio[944]: info: Received an update from plugin. extracting info from payload Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: CoreStateMachine::pushState Jan 25 13:31:11 volumio volumio[944]: info: CoreCommandRouter::volumioPushState Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 220ms Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 217ms Jan 25 13:31:11 volumio volumio[944]: info: ------------------------------ 215ms Jan 25 13:31:12 volumio sudo[5771]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jan 25 13:31:12 volumio sudo[5771]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:31:12 volumio sudo[5771]: pam_unix(sudo:session): session closed for user root Jan 25 13:31:12 volumio sudo[5773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jan 25 13:31:12 volumio sudo[5773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:31:12 volumio volumio[944]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jan 25 13:31:12 volumio volumio[944]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 13:31:12 volumio sudo[5775]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jan 25 13:31:12 volumio sudo[5775]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:31:12 volumio sudo[5778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jan 25 13:31:12 volumio volumio[944]: TypeError: Cannot read properties of undefined (reading 'split') Jan 25 13:31:12 volumio volumio[944]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Jan 25 13:31:12 volumio volumio[944]: at ChildProcess.exithandler (node:child_process:413:7) Jan 25 13:31:12 volumio volumio[944]: at ChildProcess.emit (node:events:514:28) Jan 25 13:31:12 volumio volumio[944]: at maybeClose (node:internal/child_process:1105:16) Jan 25 13:31:12 volumio volumio[944]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Jan 25 13:31:12 volumio volumio[944]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 13:31:12 volumio sudo[5778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 13:31:12 volumio systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jan 25 13:31:12 volumio systemd[1]: Started getty@tty1.service - Getty on tty1. Jan 25 13:31:12 volumio sudo[5773]: pam_unix(sudo:session): session closed for user root Jan 25 13:31:12 volumio systemd[1]: Reloading. Jan 25 13:31:14 volumio sudo[5814]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 13:30' Jan 25 13:31:14 volumio sudo[5814]: 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"