-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sun 2026-05-31 08:55:51 CEST. -- May 31 08:54:03 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:05 vkuca volumio[1227]: info: Enabling MyMusic plugin tidalconnect May 31 08:54:05 vkuca volumio[1227]: info: [MyVolumio PluginManager] Enabling and starting plugin music_service tidalconnect May 31 08:54:05 vkuca volumio[1227]: info: Enabling plugin tidalconnect May 31 08:54:05 vkuca volumio[1227]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 31 08:54:06 vkuca systemd[1]: Starting Cleanup of Temporary Directories... May 31 08:54:06 vkuca volumio[1227]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 08:54:06 vkuca sudo[4906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 31 08:54:06 vkuca sudo[4906]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:06 vkuca systemd[1]: Stopping Volumio Tidal Connect Service... May 31 08:54:06 vkuca systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM May 31 08:54:06 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:06 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:06 vkuca volumio[1227]: ------------------------------------ BT MESSAGE: BT STATUS: running May 31 08:54:06 vkuca sudo[4906]: pam_unix(sudo:session): session closed for user root May 31 08:54:06 vkuca systemd[1]: systemd-tmpfiles-clean.service: Succeeded. May 31 08:54:06 vkuca systemd[1]: Started Cleanup of Temporary Directories. May 31 08:54:07 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:09 vkuca volumio[1227]: info: TidalConnect service stoped! May 31 08:54:09 vkuca volumio[1227]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 31 08:54:09 vkuca volumio[1227]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect May 31 08:54:09 vkuca sudo[4921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 31 08:54:09 vkuca sudo[4921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:09 vkuca systemd[1]: Started Volumio Tidal Connect Service. May 31 08:54:09 vkuca sudo[4921]: pam_unix(sudo:session): session closed for user root May 31 08:54:09 vkuca volumio[1227]: info: Executing endpoint tc_getconfig May 31 08:54:09 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 08:54:09 vkuca vtcs[4923]: BYE May 31 08:54:10 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:10 vkuca systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 31 08:54:10 vkuca systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 1. May 31 08:54:10 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:10 vkuca systemd[1]: Started Volumio Tidal Connect Service. May 31 08:54:10 vkuca volumio[1227]: info: Executing endpoint tc_getconfig May 31 08:54:10 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 08:54:10 vkuca vtcs[4941]: BYE May 31 08:54:10 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:10 vkuca systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 31 08:54:10 vkuca systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 2. May 31 08:54:10 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:10 vkuca systemd[1]: Started Volumio Tidal Connect Service. May 31 08:54:11 vkuca volumio[1227]: info: Executing endpoint tc_getconfig May 31 08:54:11 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 08:54:11 vkuca vtcs[4944]: BYE May 31 08:54:11 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:11 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:11 vkuca systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 31 08:54:11 vkuca systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 3. May 31 08:54:11 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:11 vkuca systemd[1]: Started Volumio Tidal Connect Service. May 31 08:54:11 vkuca volumio[1227]: info: Executing endpoint tc_getconfig May 31 08:54:11 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 08:54:11 vkuca vtcs[4947]: BYE May 31 08:54:11 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:11 vkuca systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 31 08:54:11 vkuca systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 4. May 31 08:54:11 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:11 vkuca systemd[1]: Started Volumio Tidal Connect Service. May 31 08:54:12 vkuca volumio[1227]: info: Executing endpoint tc_getconfig May 31 08:54:12 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 31 08:54:12 vkuca vtcs[4984]: BYE May 31 08:54:12 vkuca systemd[1]: vtcs.service: Succeeded. May 31 08:54:12 vkuca systemd[1]: vtcs.service: Service RestartSec=100ms expired, scheduling restart. May 31 08:54:12 vkuca systemd[1]: vtcs.service: Scheduled restart job, restart counter is at 5. May 31 08:54:12 vkuca systemd[1]: Stopped Volumio Tidal Connect Service. May 31 08:54:12 vkuca systemd[1]: vtcs.service: Start request repeated too quickly. May 31 08:54:12 vkuca systemd[1]: vtcs.service: Failed with result 'start-limit-hit'. May 31 08:54:12 vkuca systemd[1]: Failed to start Volumio Tidal Connect Service. May 31 08:54:12 vkuca volumio[1227]: info: TidalConnect service started! May 31 08:54:12 vkuca volumio[1227]: info: Done. May 31 08:54:15 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:19 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:23 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:27 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats May 31 08:54:28 vkuca volumio[1227]: info: CoreCommandRouter::volumioGetState May 31 08:54:31 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:54:31 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:54:31 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:54:31 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:54:34 vkuca volumio[1227]: info: CoreCommandRouter::getUIConfigOnPlugin May 31 08:54:34 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 31 08:54:34 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 31 08:54:34 vkuca sudo[5036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 31 08:54:34 vkuca sudo[5036]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca sudo[5041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 08:54:34 vkuca sudo[5041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca sudo[5036]: pam_unix(sudo:session): session closed for user root May 31 08:54:34 vkuca sudo[5049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 08:54:34 vkuca sudo[5049]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca sudo[5041]: pam_unix(sudo:session): session closed for user root May 31 08:54:34 vkuca sudo[5049]: pam_unix(sudo:session): session closed for user root May 31 08:54:34 vkuca sudo[5055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 31 08:54:34 vkuca sudo[5055]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca sudo[5055]: pam_unix(sudo:session): session closed for user root May 31 08:54:34 vkuca sudo[5062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 08:54:34 vkuca sudo[5062]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 31 08:54:34 vkuca sudo[5062]: pam_unix(sudo:session): session closed for user root May 31 08:54:34 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 31 08:54:34 vkuca sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 08:54:34 vkuca sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:34 vkuca sudo[5065]: pam_unix(sudo:session): session closed for user root May 31 08:54:35 vkuca sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 08:54:35 vkuca sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:54:38 vkuca sudo[5068]: pam_unix(sudo:session): session closed for user root May 31 08:54:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioGetState May 31 08:54:50 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:54:50 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:54:50 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:54:50 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:54:51 vkuca volumio[1227]: info: Getting current volume May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioGetState May 31 08:54:51 vkuca volumio[1227]: info: Current volume is 45 May 31 08:54:51 vkuca volumio[1227]: info: Increasing volume by 1: setting volume to 46 May 31 08:54:51 vkuca volumio[1227]: info: Sending REST command to localhost:3000/api/v1/commands/?cmd=volume&volume=46 May 31 08:54:51 vkuca volumio[1227]: info: May 31 08:54:51 vkuca volumio[1227]: ---------------------------- Client requests Volume 46 May 31 08:54:51 vkuca volumio[1227]: info: VolumeController::SetAlsaVolume46 May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:54:51 vkuca volumio[1227]: info: Received response: {"time":1780210491024,"response":"volume Success"} May 31 08:54:51 vkuca volumio[1227]: info: Volume set to 46 May 31 08:54:51 vkuca volumio[1227]: info: May 31 08:54:51 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:54:51 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:54:51 vkuca volumio[1227]: info: May 31 08:54:51 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:54:51 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:54:51 vkuca volumio[1227]: info: sendMpdCommand status took 9 milliseconds May 31 08:54:51 vkuca volumio[1227]: info: sendMpdCommand status took 7 milliseconds May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:54:51 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 08:54:51 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:54:51 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:54:51 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:54:51 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:54:51 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":207108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Van Halen - Jump","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:54:51 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:54:51 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:54:51 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:54:51 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:54:51 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":207108,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Van Halen - Jump","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:54:51 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:54:51 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:54:51 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:54:51 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:54:51 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:54:51 vkuca volumio[1227]: info: ------------------------------ 179ms May 31 08:54:51 vkuca volumio[1227]: info: ------------------------------ 178ms May 31 08:54:56 vkuca nmbd[759]: [2026/05/31 08:54:56.953111, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) May 31 08:54:56 vkuca nmbd[759]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.8.5 for name WORKGROUP<1d>. May 31 08:54:56 vkuca nmbd[759]: This response was from IP 192.168.8.223, reporting an IP address of 192.168.8.223. May 31 08:55:08 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 08:55:08 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 08:55:12 vkuca volumio[1227]: info: SleepWakePlugin - onStop May 31 08:55:12 vkuca volumio[1227]: info: Disabling plugin SleepWakePlugin May 31 08:55:12 vkuca volumio[1227]: info: Done. May 31 08:55:15 vkuca volumio[1227]: info: Starting Uninstall of plugin system_controller - SleepWakePlugin May 31 08:55:15 vkuca volumio[1227]: info: Uninstalling plugin SleepWakePlugin May 31 08:55:15 vkuca volumio[1227]: info: Disabling plugin SleepWakePlugin May 31 08:55:15 vkuca volumio[1227]: info: Checking if uninstall.sh is present May 31 08:55:15 vkuca volumio[1227]: info: Executing uninstall.sh May 31 08:55:15 vkuca sudo[5203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/system_controller/SleepWakePlugin/uninstall.sh May 31 08:55:15 vkuca sudo[5203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 08:55:15 vkuca sudo[5203]: pam_unix(sudo:session): session closed for user root May 31 08:55:15 vkuca volumio[1227]: info: Uninstall script completed May 31 08:55:15 vkuca volumio[1227]: info: Removing plugin SleepWakePlugin from configuration May 31 08:55:15 vkuca volumio[1227]: info: Successfully removed SleepWakePlugin configuration files May 31 08:55:15 vkuca volumio[1227]: info: Plugin folders cleanup May 31 08:55:15 vkuca volumio[1227]: info: Scanning into folder /volumio/app/plugins/ May 31 08:55:15 vkuca volumio[1227]: info: Scanning category audio_interface May 31 08:55:15 vkuca volumio[1227]: info: Scanning category miscellanea May 31 08:55:15 vkuca volumio[1227]: info: Scanning category music_service May 31 08:55:15 vkuca volumio[1227]: info: Scanning category plugins.json May 31 08:55:15 vkuca volumio[1227]: info: Scanning category system_controller May 31 08:55:15 vkuca volumio[1227]: info: Scanning category user_interface May 31 08:55:15 vkuca volumio[1227]: info: Scanning into folder /data/plugins/ May 31 08:55:15 vkuca volumio[1227]: info: Scanning category music_service May 31 08:55:15 vkuca volumio[1227]: info: Scanning category system_controller May 31 08:55:15 vkuca volumio[1227]: info: Cleaning folder for SleepWakePlugin May 31 08:55:15 vkuca volumio[1227]: info: Scanning category system_hardware May 31 08:55:15 vkuca volumio[1227]: info: Scanning category user_interface May 31 08:55:15 vkuca volumio[1227]: info: Plugin folders cleanup completed May 31 08:55:15 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 31 08:55:15 vkuca volumio[1227]: info: [{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.2.3","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"AutoStart","name":"autostart","category":"system_controller","version":"3.0.4","icon":"fa-lightbulb-o","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"MPD OLED","name":"mpd_oled","category":"system_hardware","version":"1.1.3","icon":"fa-television","isManuallyInstalled":false,"enabled":false,"active":false},{"prettyName":"Rotary Encoder II","name":"rotaryencoder2","category":"system_hardware","version":"1.1.23","icon":"fa-circle-o-notch","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"PeppyMeter Screensaver","name":"peppy_screensaver","category":"user_interface","version":"2.2.1","icon":"fa-lightbulb-o","isManuallyInstalled":true,"enabled":true,"active":true},{"prettyName":"Touch Display","name":"touch_display","category":"user_interface","version":"3.5.1","icon":"fa-hand-pointer-o","isManuallyInstalled":false,"enabled":true,"active":true}] May 31 08:55:15 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken May 31 08:55:32 vkuca volumio[1227]: info: May 31 08:55:32 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:32 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:32 vkuca volumio[1227]: info: May 31 08:55:32 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:32 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:32 vkuca volumio[1227]: info: May 31 08:55:32 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:32 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:32 vkuca volumio[1227]: info: May 31 08:55:32 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:32 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:32 vkuca volumio[1227]: info: ------------------------------ 5ms May 31 08:55:32 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:32 vkuca volumio[1227]: info: ------------------------------ 4ms May 31 08:55:32 vkuca volumio[1227]: info: sendMpdCommand status took 3 milliseconds May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:32 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:32 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:32 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:32 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:32 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:32 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:32 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":248902,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:32 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:32 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:32 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:32 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:32 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:32 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:32 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:32 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:32 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:32 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:32 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:32 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:32 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:33 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:33 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:33 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:33 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:33 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":248902,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:33 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:33 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:33 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:33 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:33 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:33 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:33 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:33 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:33 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:33 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:33 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:33 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:33 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:33 vkuca volumio[1227]: info: ------------------------------ 123ms May 31 08:55:33 vkuca volumio[1227]: info: ------------------------------ 121ms May 31 08:55:35 vkuca volumio[1227]: info: May 31 08:55:35 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:35 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:35 vkuca volumio[1227]: info: May 31 08:55:35 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:35 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:35 vkuca volumio[1227]: info: May 31 08:55:35 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:35 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:35 vkuca volumio[1227]: info: May 31 08:55:35 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:35 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:35 vkuca volumio[1227]: info: ------------------------------ 5ms May 31 08:55:35 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:35 vkuca volumio[1227]: info: ------------------------------ 4ms May 31 08:55:35 vkuca volumio[1227]: info: sendMpdCommand status took 2 milliseconds May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:35 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:35 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:35 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:35 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:35 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:35 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251410,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:35 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:35 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:35 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:35 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:35 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":251410,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:35 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:35 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:35 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:35 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:35 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:35 vkuca volumio[1227]: info: ------------------------------ 133ms May 31 08:55:35 vkuca volumio[1227]: info: ------------------------------ 131ms May 31 08:55:38 vkuca volumio[1227]: info: May 31 08:55:38 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:38 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:38 vkuca volumio[1227]: info: May 31 08:55:38 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:38 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:38 vkuca volumio[1227]: info: May 31 08:55:38 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:38 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:38 vkuca volumio[1227]: info: May 31 08:55:38 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:38 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:38 vkuca volumio[1227]: info: ------------------------------ 5ms May 31 08:55:38 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:38 vkuca volumio[1227]: info: ------------------------------ 3ms May 31 08:55:38 vkuca volumio[1227]: info: sendMpdCommand status took 2 milliseconds May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:38 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:38 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:38 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:38 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:38 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:38 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":254013,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:38 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:38 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:38 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:38 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:38 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":254013,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"ANTENNE BAYERN - Greatest Hits","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:38 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:38 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:38 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:38 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:38 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:38 vkuca volumio[1227]: info: ------------------------------ 137ms May 31 08:55:38 vkuca volumio[1227]: info: ------------------------------ 137ms May 31 08:55:43 vkuca volumio[1227]: info: May 31 08:55:43 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:43 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:43 vkuca volumio[1227]: info: May 31 08:55:43 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:43 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:43 vkuca volumio[1227]: info: May 31 08:55:43 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:43 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:43 vkuca volumio[1227]: info: May 31 08:55:43 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:43 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:43 vkuca volumio[1227]: info: ------------------------------ 5ms May 31 08:55:43 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:43 vkuca volumio[1227]: info: ------------------------------ 4ms May 31 08:55:43 vkuca volumio[1227]: info: sendMpdCommand status took 2 milliseconds May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:43 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 1 milliseconds May 31 08:55:43 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:43 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:43 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:43 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:43 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":259209,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:43 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:43 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:43 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:43 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:43 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":259209,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:43 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:43 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:43 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:43 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:43 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:43 vkuca volumio[1227]: info: ------------------------------ 133ms May 31 08:55:43 vkuca volumio[1227]: info: ------------------------------ 132ms May 31 08:55:45 vkuca volumio[1227]: info: May 31 08:55:45 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:45 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:45 vkuca volumio[1227]: info: May 31 08:55:45 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:45 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:45 vkuca volumio[1227]: info: May 31 08:55:45 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:45 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:45 vkuca volumio[1227]: info: May 31 08:55:45 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:45 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:45 vkuca volumio[1227]: info: ------------------------------ 5ms May 31 08:55:45 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:45 vkuca volumio[1227]: info: ------------------------------ 3ms May 31 08:55:45 vkuca volumio[1227]: info: sendMpdCommand status took 3 milliseconds May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:45 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 08:55:45 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 2 milliseconds May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:45 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:45 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:45 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:45 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":261443,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:45 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:45 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:45 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:45 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:45 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":261443,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:45 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:45 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:45 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:45 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:45 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:45 vkuca volumio[1227]: info: ------------------------------ 153ms May 31 08:55:45 vkuca volumio[1227]: info: ------------------------------ 152ms May 31 08:55:46 vkuca volumio[1227]: info: CoreCommandRouter::volumioGetState May 31 08:55:48 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:55:48 vkuca volumio[1227]: info: Executing endpoint metavolumio May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:48 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 3ms May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 2 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces system playlist update May 31 08:55:48 vkuca volumio[1227]: info: Ignoring MPD Status Update May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: player May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 3ms May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 3 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:48 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:48 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264316,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:48 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:48 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 78ms May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 71 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:48 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:48 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264316,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:48 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:48 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 136ms May 31 08:55:48 vkuca volumio[1227]: info: VolumeController::SetAlsaVolume44 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 12 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 7 milliseconds May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:48 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:48 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264499,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:48 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:48 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 55ms May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 38 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:48 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:48 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264599,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:48 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:48 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: ------------------------------ 78ms May 31 08:55:48 vkuca volumio[1227]: info: VolumeController::SetAlsaVolume43 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:48 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: May 31 08:55:48 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 5 milliseconds May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand status took 3 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 08:55:48 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 5 milliseconds May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:48 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:48 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:48 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264873,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:48 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:48 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:48 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:48 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:49 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:49 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":264942,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:49 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:49 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: ------------------------------ 71ms May 31 08:55:49 vkuca volumio[1227]: info: ------------------------------ 71ms May 31 08:55:49 vkuca volumio[1227]: info: VolumeController::SetAlsaVolume42 May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: May 31 08:55:49 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:49 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:49 vkuca volumio[1227]: info: May 31 08:55:49 vkuca volumio[1227]: ---------------------------- MPD announces state update: mixer May 31 08:55:49 vkuca volumio[1227]: info: ControllerMpd::getState May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand status May 31 08:55:49 vkuca volumio[1227]: info: sendMpdCommand status took 4 milliseconds May 31 08:55:49 vkuca volumio[1227]: info: sendMpdCommand status took 2 milliseconds May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::parseState May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 31 08:55:49 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 4 milliseconds May 31 08:55:49 vkuca volumio[1227]: info: sendMpdCommand playlistinfo took 3 milliseconds May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:49 vkuca volumio[1227]: verbose: ControllerMpd::parseTrackInfo May 31 08:55:49 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:49 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:49 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":265433,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:49 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:49 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: ControllerMpd::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::servicePushState May 31 08:55:49 vkuca volumio[1227]: info: CorePlayQueue::getTrack 0 May 31 08:55:49 vkuca volumio[1227]: verbose: STATE SERVICE {"status":"play","position":0,"seek":265433,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"Taylor Swift - Blank Space (Taylors Version)","artist":"ANTENNE BAYERN Greatest Hits","album":null,"uri":"http://stream.antenne.de:80/greatest-hits","trackType":""} May 31 08:55:49 vkuca volumio[1227]: verbose: CURRENT POSITION 0 May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState stateService play May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::syncState currentStatus play May 31 08:55:49 vkuca volumio[1227]: info: Received an update from plugin. extracting info from payload May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: CoreStateMachine::pushState May 31 08:55:49 vkuca volumio[1227]: info: CoreCommandRouter::volumioPushState May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output update for this device May 31 08:55:49 vkuca volumio[1227]: info: MRS: Pushing multiroomSync output May 31 08:55:49 vkuca volumio[1227]: info: ------------------------------ 56ms May 31 08:55:49 vkuca volumio[1227]: info: ------------------------------ 55ms May 31 08:55:51 vkuca volumio[1227]: info: Getting current volume May 31 08:55:51 vkuca volumio[1227]: error: SleepWakePlugin - Error in increaseVolume: Error: ENOENT: no such file or directory, open '/data/plugins/system_controller/SleepWakePlugin/sleep-wake-plugin.log' May 31 08:55:51 vkuca volumio[1227]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 08:55:51 vkuca volumio[1227]: Error: ENOENT: no such file or directory, open '/data/plugins/system_controller/SleepWakePlugin/sleep-wake-plugin.log' May 31 08:55:51 vkuca volumio[1227]: at Object.openSync (fs.js:476:3) May 31 08:55:51 vkuca volumio[1227]: at Object.writeFileSync (fs.js:1467:35) May 31 08:55:51 vkuca volumio[1227]: at Object.appendFileSync (fs.js:1506:6) May 31 08:55:51 vkuca volumio[1227]: at SleepWakePlugin.writeLog (/data/plugins/system_controller/SleepWakePlugin/index.js:827:6) May 31 08:55:51 vkuca volumio[1227]: at Timeout.increaseVolume [as _onTimeout] (/data/plugins/system_controller/SleepWakePlugin/index.js:802:12) May 31 08:55:51 vkuca volumio[1227]: at listOnTimeout (internal/timers.js:554:17) May 31 08:55:51 vkuca volumio[1227]: at processTimers (internal/timers.js:497:7) { May 31 08:55:51 vkuca volumio[1227]: errno: -2, May 31 08:55:51 vkuca volumio[1227]: syscall: 'open', May 31 08:55:51 vkuca volumio[1227]: code: 'ENOENT', May 31 08:55:51 vkuca volumio[1227]: path: '/data/plugins/system_controller/SleepWakePlugin/sleep-wake-plugin.log' May 31 08:55:51 vkuca volumio[1227]: } May 31 08:55:51 vkuca volumio[1227]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 08:55:51 vkuca sudo[5425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-31 08:54 May 31 08:55:51 vkuca sudo[5425]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"