Dec 22 11:01:00 volumio-erg sudo[7805]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cp -p /data/plugins/system_hardware/mpd_oled/mpd_oled /usr/local/bin/mpd_oled Dec 22 11:01:00 volumio-erg sudo[7805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:01:00 volumio-erg sudo[7805]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:00 volumio-erg sudo[7807]: root : PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/chmod +x /usr/local/bin/mpd_oled Dec 22 11:01:00 volumio-erg sudo[7807]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:01:00 volumio-erg sudo[7807]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:00 volumio-erg volumio[1166]: info: Checking I2C busses Dec 22 11:01:00 volumio-erg volumio[1166]: info: * I2C-1 bus enabled Dec 22 11:01:00 volumio-erg volumio[1166]: info: * I2C-0 bus enabled Dec 22 11:01:00 volumio-erg volumio[1166]: info: Setting I2C baudrate Dec 22 11:01:00 volumio-erg sudo[7453]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:00 volumio-erg volumio[1166]: info: Enabling SPI Dec 22 11:01:00 volumio-erg volumio[1166]: Plugin install end detected on script Dec 22 11:01:00 volumio-erg volumio[1166]: info: Install script completed Dec 22 11:01:00 volumio-erg volumio[1166]: info: Adding reference to registry Dec 22 11:01:00 volumio-erg volumio[1166]: info: Done installing plugin. Dec 22 11:01:00 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:01:01 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 11:01:01 volumio-erg volumio[1166]: info: Folder /tmp/plugins removed Dec 22 11:01:01 volumio-erg volumio[1166]: info: Folder /tmp/downloaded_plugin.zip removed Dec 22 11:01:01 volumio-erg volumio[1166]: info: Folder /data/temp removed Dec 22 11:01:02 volumio-erg volumio[1166]: info: Enabling plugin mpd_oled Dec 22 11:01:02 volumio-erg volumio[1166]: info: Loading plugin "mpd_oled"... Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of OLED_TYPE to Select... Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of NUMBER_OF_BARS to 16 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of GAP_BETWEEN_BARS to 1 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of FRAME_RATE to 50 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of SCROLL_RATE to 8 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of SCROLL_DELAY to 5 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of CLOCK_FORMAT to 24h leading 0 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of PAUSE_SCREEN_TYPE to Play (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of INVERT_DISPLAY_PERIOD to Disabled (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of I2C_ADDRESS to Default for display Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of I2C_BUS to /dev/i2c-1 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of SPI_RESET_GPIO_NUMBER to 25 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of SPI_DC_GPIO_NUMBER to 24 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of SPI_CS to 0 (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Translated default label of DATE_FORMAT to DD-MM-YYYY (default) Dec 22 11:01:04 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:05 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:05 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:05 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:05 volumio-erg volumio[1166]: info: Asound.conf file written Dec 22 11:01:05 volumio-erg sudo[7822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 22 11:01:05 volumio-erg sudo[7822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:05 volumio-erg sudo[7822]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:05 volumio-erg volumio[1166]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 11:01:05 volumio-erg volumio[1166]: No state is present for card b1 Dec 22 11:01:05 volumio-erg volumio[1166]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 22 11:01:05 volumio-erg volumio[1166]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 22 11:01:05 volumio-erg volumio[1166]: No state is present for card b1 Dec 22 11:01:05 volumio-erg volumio[1166]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 11:01:05 volumio-erg volumio[1166]: No state is present for card Headphones Dec 22 11:01:05 volumio-erg volumio[1166]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 22 11:01:05 volumio-erg volumio[1166]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 22 11:01:05 volumio-erg volumio[1166]: No state is present for card Headphones Dec 22 11:01:05 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:05 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:05 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:05 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:05 volumio-erg sudo[7830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:05 volumio-erg sudo[7830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:05 volumio-erg sudo[7830]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:05 volumio-erg sudo[7832]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:05 volumio-erg sudo[7832]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:05 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:05 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:05 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:05 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:05 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:05 volumio-erg sudo[7842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:05 volumio-erg sudo[7842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg volumio[1166]: info: PLUGIN START: mpd_oled Dec 22 11:01:06 volumio-erg systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 11:01:06 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Dec 22 11:01:06 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:06 volumio-erg volumio[1166]: info: Done. Dec 22 11:01:06 volumio-erg systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 22 11:01:06 volumio-erg systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 22 11:01:06 volumio-erg systemd[1]: vtcs.service: Deactivated successfully. Dec 22 11:01:06 volumio-erg systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 22 11:01:06 volumio-erg systemd[1]: vtcs.service: Consumed 1.735s CPU time. Dec 22 11:01:06 volumio-erg sudo[7851]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:06 volumio-erg sudo[7851]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg sudo[7842]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:06 volumio-erg sudo[7864]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:06 volumio-erg sudo[7864]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:06 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:06 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:06 volumio-erg volumio[1166]: info: Enabling plugin mpd_oled Dec 22 11:01:06 volumio-erg volumio[1166]: info: Loading plugin "mpd_oled"... Dec 22 11:01:06 volumio-erg volumio[1166]: info: Applying required configuration parameters for plugin mpd_oled Dec 22 11:01:06 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 22 11:01:06 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:06 volumio-erg sudo[7864]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:06 volumio-erg sudo[7851]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg sudo[7875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:06 volumio-erg sudo[7875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:06 volumio-erg volumio[1166]: info: Asound.conf file unchanged, so no further update is needed Dec 22 11:01:06 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:06 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:06 volumio-erg sudo[7886]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:06 volumio-erg sudo[7886]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg sudo[7888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:06 volumio-erg sudo[7888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg sudo[7890]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:06 volumio-erg sudo[7890]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:06 volumio-erg sudo[7888]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:06 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:06 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:06 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:07 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:07 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:07 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:07 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:07 volumio-erg systemd[1]: shairport-sync.service: Consumed 8.730s CPU time. Dec 22 11:01:07 volumio-erg sudo[7902]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:07 volumio-erg sudo[7902]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:07 volumio-erg sudo[7908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:07 volumio-erg sudo[7908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg sudo[7886]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:07 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:07 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:07 volumio-erg sudo[7914]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:07 volumio-erg sudo[7914]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:07 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg sudo[7914]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:07 volumio-erg sudo[7922]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:07 volumio-erg sudo[7922]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg volumio[1166]: info: Asound.conf file unchanged, so no further update is needed Dec 22 11:01:07 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:07 volumio-erg sudo[7925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:07 volumio-erg sudo[7925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg sudo[7908]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:07 volumio-erg sudo[7927]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:07 volumio-erg sudo[7902]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg sudo[7927]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg sudo[7927]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg sudo[7931]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:07 volumio-erg sudo[7931]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:07 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:07 volumio-erg qobuz-connect[1764]: 20251222 11:01:07.555 [1764.1764] INFO SampleApp: Stopping Local configuration server Dec 22 11:01:07 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:07 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:07 volumio-erg volumio[1166]: info: PLUGIN START: mpd_oled Dec 22 11:01:07 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:07 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:07 volumio-erg sudo[7944]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:07 volumio-erg sudo[7944]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg volumio[1166]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Dec 22 11:01:07 volumio-erg volumio[1166]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Dec 22 11:01:07 volumio-erg volumio[1166]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Dec 22 11:01:07 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 11:01:07 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Dec 22 11:01:07 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:07 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:07 volumio-erg sudo[7925]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: Done. Dec 22 11:01:07 volumio-erg sudo[7951]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:07 volumio-erg sudo[7951]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg sudo[7944]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:07 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:07 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:07 volumio-erg sudo[7958]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:07 volumio-erg sudo[7958]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:07 volumio-erg sudo[7951]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:07 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:07 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg sudo[7958]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:08 volumio-erg sudo[7963]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:08 volumio-erg sudo[7963]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg volumio[1166]: info: Asound.conf file unchanged, so no further update is needed Dec 22 11:01:08 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:08 volumio-erg sudo[7970]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:08 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:08 volumio-erg sudo[7970]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg sudo[7972]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:08 volumio-erg qobuz-connect[1764]: 20251222 11:01:08.155 [1764.1764] INFO SampleApp: shat down connection on UNIX socket Dec 22 11:01:08 volumio-erg sudo[7972]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg sudo[7974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:08 volumio-erg sudo[7974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg sudo[7972]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:08 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:08 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:08 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:08 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:08 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:08 volumio-erg sudo[7970]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg sudo[7989]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:08 volumio-erg sudo[7987]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:08 volumio-erg sudo[7989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg sudo[7987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:08 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:08 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 11:01:08 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:08 volumio-erg sudo[7997]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:08 volumio-erg sudo[7997]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:08 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:08 volumio-erg sudo[7997]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg sudo[7987]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg sudo[8006]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:08 volumio-erg sudo[8006]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg sudo[8005]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:08 volumio-erg sudo[7989]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg sudo[8005]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:08 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:08 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:08 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:08 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:08 volumio-erg sudo[8005]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:08 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:09 volumio-erg systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 11:01:09 volumio-erg systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:09 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:09 volumio-erg systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:09 volumio-erg sudo[7875]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:09 volumio-erg sudo[8006]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:09 volumio-erg sudo[7963]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:09 volumio-erg sudo[7922]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:09 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:09 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:09 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:09 volumio-erg sudo[8052]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:09 volumio-erg sudo[8052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:09 volumio-erg systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 22 11:01:09 volumio-erg sudo[8052]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:10 volumio-erg volumio[1166]: info: Executing endpoint qc_getconfig Dec 22 11:01:10 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.229 [8037.8037] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 11:01:10 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 11:01:10 volumio-erg volumio[1166]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.305 [8037.8037] INFO VolumeManager: [0xe0258]: Setting new playback volume: 75 Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.306 [8037.8037] INFO VolumeManager: [0xe0258]: Setting new mute state: 0 Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.306 [8037.8037] INFO QobuzConnect: [0xe0c28]: Client initialized! Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.307 [8037.8037] INFO SampleApp: Starting Avahi advertising, name: Volumio erg, service name: _qobuz-connect._tcp Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.338 [8037.8037] INFO LocalConfigManager: [0xdfb38]: Starting Local Configuration server Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.338 [8037.8037] INFO SampleApp: Starting Local configuration server Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.339 [8037.8037] INFO SampleApp: Connected to UNIX socket client 0xca818 Dec 22 11:01:10 volumio-erg qobuz-connect[8037]: 20251222 11:01:10.380 [8037.8037] INFO SampleApp: Playback volume changed: 75 Dec 22 11:01:10 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:10 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:10 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:10 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:10 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:10 volumio-erg sudo[8073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:10 volumio-erg sudo[8073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:10 volumio-erg sudo[8073]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:10 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:11 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:11 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:11 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:11 volumio-erg sudo[8085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:11 volumio-erg sudo[8085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:11 volumio-erg sudo[8085]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:11 volumio-erg volumio[1166]: info: Executing endpoint tc_getconfig Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 22 11:01:11 volumio-erg vtcs[8055]: STARTING TidalConnect services, version: 1.5.2.56 Dec 22 11:01:11 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:11 volumio-erg vtcs[8055]: STARTED TidalConnect services. Dec 22 11:01:11 volumio-erg volumio[1166]: info: Executing endpoint tc_connect Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 22 11:01:11 volumio-erg volumio[1166]: info: Connecting to TidalConnect Dec 22 11:01:11 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:11 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:11 volumio-erg volumio[1166]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:11 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:11 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:11 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:11 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:11 volumio-erg volumio[1166]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 22 11:01:11 volumio-erg systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 22 11:01:11 volumio-erg systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 22 11:01:11 volumio-erg systemd[1]: setdatetime-helper.service: Consumed 2.405s CPU time. Dec 22 11:01:11 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:11 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:12 volumio-erg sudo[8120]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:12 volumio-erg sudo[8120]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:12 volumio-erg sudo[8120]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:12 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:13 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:14 volumio-erg systemd[1]: mpd.service: Deactivated successfully. Dec 22 11:01:14 volumio-erg systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 11:01:14 volumio-erg systemd[1]: mpd.service: Consumed 16.135s CPU time. Dec 22 11:01:14 volumio-erg systemd[1]: mpd.socket: Deactivated successfully. Dec 22 11:01:14 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:14 volumio-erg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 11:01:14 volumio-erg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 11:01:14 volumio-erg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 11:01:14 volumio-erg systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 11:01:14 volumio-erg sudo[8124]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 11:01:14 volumio-erg sudo[8124]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:01:14 volumio-erg sudo[8124]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:15 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:17 volumio-erg volumio[1166]: info: Enabling plugin mpd_oled Dec 22 11:01:17 volumio-erg volumio[1166]: info: Loading plugin "mpd_oled"... Dec 22 11:01:17 volumio-erg volumio[1166]: info: Applying required configuration parameters for plugin mpd_oled Dec 22 11:01:17 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Reading configuration file at: /data/configuration/system_hardware/mpd_oled/config.json Dec 22 11:01:17 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:17 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:17 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:17 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:17 volumio-erg volumio[1166]: info: Asound.conf file unchanged, so no further update is needed Dec 22 11:01:17 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:17 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:17 volumio-erg sudo[8128]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:17 volumio-erg sudo[8130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:17 volumio-erg sudo[8128]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg sudo[8130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:17 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg sudo[8128]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:17 volumio-erg vtcs[8055]: [2025-12-22 11:01:17.422] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 22 11:01:17 volumio-erg vtcs[8055]: [2025-12-22 11:01:17.423] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 22 11:01:17 volumio-erg systemd[1]: mpd.service: Deactivated successfully. Dec 22 11:01:17 volumio-erg systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 11:01:17 volumio-erg systemd[1]: mpd.service: Consumed 2.632s CPU time. Dec 22 11:01:17 volumio-erg systemd[1]: mpd.socket: Deactivated successfully. Dec 22 11:01:17 volumio-erg volumio[1166]: info: PLUGIN START: mpd_oled Dec 22 11:01:17 volumio-erg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 11:01:17 volumio-erg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 11:01:17 volumio-erg volumio[1166]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Dec 22 11:01:17 volumio-erg volumio[1166]: error: [MPD_OLED Plugin] Failed to create mpdoledfifo: Error: Command failed: /usr/bin/mkfifo -m 646 /tmp/mpdoledfifo Dec 22 11:01:17 volumio-erg volumio[1166]: /usr/bin/mkfifo: cannot create fifo '/tmp/mpdoledfifo': File exists Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 22 11:01:17 volumio-erg volumio[1166]: info: [MPD_OLED Plugin] Not starting mpd_oled service because oled type is not configured yet Dec 22 11:01:17 volumio-erg volumio[1166]: info: Preparing to generate the ALSA configuration file Dec 22 11:01:17 volumio-erg sudo[8140]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:17 volumio-erg sudo[8140]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg volumio[1166]: info: Done. Dec 22 11:01:17 volumio-erg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 11:01:17 volumio-erg systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 11:01:17 volumio-erg sudo[8147]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:17 volumio-erg sudo[8147]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg volumio[1166]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 22 11:01:17 volumio-erg volumio[1166]: info: The plugin mpd_oled has an ALSA contribution file volumioalsa.postalsa.5.conf Dec 22 11:01:17 volumio-erg volumio[1166]: info: Reading ALSA contributions from plugins. Dec 22 11:01:17 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg sudo[8155]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:17 volumio-erg sudo[8155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg systemd[1]: vtcs.service: Killing process 8098 (n/a) with signal SIGKILL. Dec 22 11:01:17 volumio-erg systemd[1]: vtcs.service: Deactivated successfully. Dec 22 11:01:17 volumio-erg systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 22 11:01:17 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:17 volumio-erg volumio[1166]: info: Asound.conf file unchanged, so no further update is needed Dec 22 11:01:17 volumio-erg volumio[1166]: info: Output device has changed, restarting MPD Dec 22 11:01:17 volumio-erg sudo[8163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:17 volumio-erg sudo[8163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg sudo[8155]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg sudo[8140]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg volumio[1166]: info: Output device has changed, restarting Shairport Sync Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:17 volumio-erg sudo[8167]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:17 volumio-erg sudo[8167]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg sudo[8166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 22 11:01:17 volumio-erg sudo[8166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg sudo[8147]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg sudo[8166]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:17 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:17 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:17 volumio-erg systemd[1]: shairport-sync.service: Consumed 1.823s CPU time. Dec 22 11:01:17 volumio-erg volumio[1166]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 22 11:01:17 volumio-erg volumio[1166]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:17 volumio-erg sudo[8149]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 11:01:17 volumio-erg sudo[8149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:01:17 volumio-erg sudo[8169]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 22 11:01:17 volumio-erg sudo[8169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:17 volumio-erg sudo[8149]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:17 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:17 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:17 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:17 volumio-erg sudo[8163]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg sudo[8184]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:18 volumio-erg sudo[8184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:18 volumio-erg systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 11:01:18 volumio-erg qobuz-connect[8037]: 20251222 11:01:18.082 [8037.8037] INFO SampleApp: Stopping Local configuration server Dec 22 11:01:18 volumio-erg sudo[8188]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 22 11:01:18 volumio-erg sudo[8188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:18 volumio-erg systemd[1]: mpd.service: Deactivated successfully. Dec 22 11:01:18 volumio-erg systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 22 11:01:18 volumio-erg systemd[1]: mpd.socket: Deactivated successfully. Dec 22 11:01:18 volumio-erg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 22 11:01:18 volumio-erg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 22 11:01:18 volumio-erg volumio[1166]: info: MPD Permissions set Dec 22 11:01:18 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg sudo[8210]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 22 11:01:18 volumio-erg sudo[8210]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 22 11:01:18 volumio-erg volumio[1166]: info: Starting Shairport Sync Dec 22 11:01:18 volumio-erg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 22 11:01:18 volumio-erg systemd[1]: Starting mpd.service - Music Player Daemon... Dec 22 11:01:18 volumio-erg sudo[8218]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 22 11:01:18 volumio-erg sudo[8218]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:18 volumio-erg qobuz-connect[8037]: 20251222 11:01:18.357 [8037.8037] INFO SampleApp: shat down connection on UNIX socket Dec 22 11:01:18 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 11:01:18 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:18 volumio-erg systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 11:01:18 volumio-erg systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:18 volumio-erg sudo[8210]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg sudo[8184]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg sudo[8222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 22 11:01:18 volumio-erg sudo[8222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:18 volumio-erg sudo[8219]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 22 11:01:18 volumio-erg sudo[8219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 22 11:01:18 volumio-erg sudo[8219]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:18 volumio-erg sudo[8167]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 22 11:01:18 volumio-erg systemd[1]: shairport-sync.service: Deactivated successfully. Dec 22 11:01:18 volumio-erg systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:18 volumio-erg volumio[1166]: info: Executing endpoint qc_getconfig Dec 22 11:01:18 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 11:01:18 volumio-erg sudo[8188]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.675 [8223.8223] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 11:01:18 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 11:01:18 volumio-erg volumio[1166]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.685 [8223.8223] INFO VolumeManager: [0x399258]: Setting new playback volume: 75 Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.685 [8223.8223] INFO VolumeManager: [0x399258]: Setting new mute state: 0 Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.685 [8223.8223] INFO QobuzConnect: [0x399c28]: Client initialized! Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.685 [8223.8223] INFO SampleApp: Starting Avahi advertising, name: Volumio erg, service name: _qobuz-connect._tcp Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.709 [8223.8223] INFO LocalConfigManager: [0x398b38]: Starting Local Configuration server Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.709 [8223.8223] INFO SampleApp: Starting Local configuration server Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.710 [8223.8223] INFO SampleApp: Connected to UNIX socket client 0x383818 Dec 22 11:01:18 volumio-erg systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 22 11:01:18 volumio-erg sudo[8218]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:18 volumio-erg volumio[1166]: info: Shairport-Sync Started Dec 22 11:01:18 volumio-erg systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 22 11:01:18 volumio-erg qobuz-connect[8223]: 20251222 11:01:18.778 [8223.8223] INFO SampleApp: Stopping Local configuration server Dec 22 11:01:20 volumio-erg qobuz-connect[8223]: 20251222 11:01:20.721 [8223.8223] INFO SampleApp: shat down connection on UNIX socket Dec 22 11:01:20 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 22 11:01:20 volumio-erg volumio[1166]: info: QobuzConnect: setDeactiveState invoked Dec 22 11:01:20 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:20 volumio-erg systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 22 11:01:20 volumio-erg systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:20 volumio-erg systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 22 11:01:20 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:20 volumio-erg sudo[8222]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:20 volumio-erg volumio[1166]: info: Executing endpoint qc_getconfig Dec 22 11:01:20 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.925 [8263.8263] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 22 11:01:20 volumio-erg volumio[1166]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 22 11:01:20 volumio-erg volumio[1166]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.935 [8263.8263] INFO VolumeManager: [0x90e258]: Setting new playback volume: 75 Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.938 [8263.8263] INFO VolumeManager: [0x90e258]: Setting new mute state: 0 Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.938 [8263.8263] INFO QobuzConnect: [0x90ec28]: Client initialized! Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.938 [8263.8263] INFO SampleApp: Starting Avahi advertising, name: Volumio erg, service name: _qobuz-connect._tcp Dec 22 11:01:20 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.973 [8263.8263] INFO LocalConfigManager: [0x90db38]: Starting Local Configuration server Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.973 [8263.8263] INFO SampleApp: Starting Local configuration server Dec 22 11:01:20 volumio-erg qobuz-connect[8263]: 20251222 11:01:20.974 [8263.8263] INFO SampleApp: Connected to UNIX socket client 0x8f8818 Dec 22 11:01:20 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:20 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:21 volumio-erg sudo[8278]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:21 volumio-erg sudo[8278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:21 volumio-erg qobuz-connect[8263]: 20251222 11:01:21.076 [8263.8263] INFO SampleApp: Playback volume changed: 75 Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:21 volumio-erg systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 22 11:01:21 volumio-erg sudo[8278]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:21 volumio-erg volumio[1166]: info: Executing endpoint tc_getconfig Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 22 11:01:21 volumio-erg vtcs[8281]: STARTING TidalConnect services, version: 1.5.2.56 Dec 22 11:01:21 volumio-erg vtcs[8281]: STARTED TidalConnect services. Dec 22 11:01:21 volumio-erg volumio[1166]: info: Executing endpoint tc_connect Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 22 11:01:21 volumio-erg volumio[1166]: info: Connecting to TidalConnect Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:21 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:21 volumio-erg volumio[1166]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:21 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:21 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:21 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:21 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:21 volumio-erg volumio[1166]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 22 11:01:21 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:21 volumio-erg volumio[1166]: info: TidalConnect service stoped! Dec 22 11:01:21 volumio-erg volumio[1166]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:21 volumio-erg volumio[1166]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 22 11:01:21 volumio-erg sudo[8306]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 22 11:01:21 volumio-erg sudo[8306]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:21 volumio-erg sudo[8306]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:24 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:24 volumio-erg volumio[1166]: info: TidalConnect service started! Dec 22 11:01:26 volumio-erg mpd[8226]: 2025-12-22T11:01:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 22 11:01:26 volumio-erg systemd[1]: Started mpd.service - Music Player Daemon. Dec 22 11:01:26 volumio-erg sudo[7832]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg sudo[7974]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg sudo[8169]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg sudo[8130]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg sudo[7931]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg sudo[7890]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:26 volumio-erg volumio[1166]: error: MPD error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: MPD error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: MPD error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: MPD error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: MPD error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: The expression evaluated to a falsy value: Dec 22 11:01:26 volumio-erg volumio[1166]: assert.ok(self.idling) Dec 22 11:01:26 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:29 volumio-erg sudo[8329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:29 volumio-erg sudo[8329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:29 volumio-erg sudo[8331]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:29 volumio-erg sudo[8331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:29 volumio-erg sudo[8333]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:29 volumio-erg sudo[8333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:29 volumio-erg sudo[8337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:29 volumio-erg sudo[8340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:29 volumio-erg sudo[8340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:29 volumio-erg sudo[8337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:30 volumio-erg sudo[8343]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 22 11:01:30 volumio-erg sudo[8343]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:30 volumio-erg systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 22 11:01:30 volumio-erg systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 22 11:01:30 volumio-erg systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 22 11:01:30 volumio-erg systemd[1]: mpd_monitor.service: Consumed 1.099s CPU time. Dec 22 11:01:30 volumio-erg systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 22 11:01:30 volumio-erg sudo[8329]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg sudo[8333]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg sudo[8343]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg sudo[8340]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg sudo[8337]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg sudo[8331]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:30 volumio-erg volumio[1166]: info: Successfully started MPD Monitor Dec 22 11:01:31 volumio-erg mpd_monitor.sh[8346]: MPD Monitor Service: Starting MPD Monitor Service Dec 22 11:01:38 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 22 11:01:38 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 22 11:01:39 volumio-erg sudo[8353]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 11:01:39 volumio-erg sudo[8353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:39 volumio-erg sudo[8353]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:39 volumio-erg sudo[8355]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 11:01:39 volumio-erg sudo[8355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:39 volumio-erg sudo[8355]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:39 volumio-erg volumio[1166]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Dec 22 11:01:39 volumio-erg sudo[8373]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 22 11:01:39 volumio-erg sudo[8373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:39 volumio-erg sudo[8373]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:39 volumio-erg sudo[8375]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 22 11:01:39 volumio-erg sudo[8375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:01:39 volumio-erg sudo[8375]: pam_unix(sudo:session): session closed for user root Dec 22 11:01:39 volumio-erg volumio[1166]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.5 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_6_2 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 22 11:01:39 volumio-erg volumio[1166]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 22 11:01:39 volumio-erg volumio[1166]: info: Received Get System Info Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:01:39 volumio-erg volumio[1166]: info: Discovery: Getting this device information Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:01:39 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:40 volumio-erg volumio[1166]: info: Listing playlists Dec 22 11:01:40 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 22 11:01:40 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:01:40 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:01:41 volumio-erg volumio[1166]: info: Received Get System Info Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:01:41 volumio-erg volumio[1166]: info: Discovery: Getting this device information Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:01:41 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 22 11:01:43 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:01:43 volumio-erg volumio[1166]: info: Received Get System Info Dec 22 11:01:43 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:01:43 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:01:43 volumio-erg volumio[1166]: info: Discovery: Getting this device information Dec 22 11:01:43 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:43 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:01:44 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetQueue Dec 22 11:01:44 volumio-erg volumio[1166]: info: CoreStateMachine::getQueue Dec 22 11:01:44 volumio-erg volumio[1166]: info: CorePlayQueue::getQueue Dec 22 11:01:46 volumio-erg volumio[1166]: info: Preload queue cleared Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::ClearQueue Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::stop Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::stPlaybackTimer Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:46 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:46 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::serviceStop Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::serviceStop Dec 22 11:01:46 volumio-erg volumio[1166]: info: [1766394106977] ControllerWebradio::stop Dec 22 11:01:46 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::clearPlayQueue Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::saveQueue Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushQueue Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreStateMachine::addQueueItems Dec 22 11:01:46 volumio-erg volumio[1166]: info: CorePlayQueue::addQueueItems Dec 22 11:01:46 volumio-erg volumio[1166]: info: Preload queue cleared Dec 22 11:01:46 volumio-erg volumio[1166]: info: Adding Item to queue: http://opml.radiotime.com/Tune.ashx?id=s191614 Dec 22 11:01:46 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Dec 22 11:01:47 volumio-erg volumio[1166]: info: sendMpdCommand stop took 270 milliseconds Dec 22 11:01:47 volumio-erg volumio[1166]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushQueue Dec 22 11:01:47 volumio-erg volumio[1166]: info: CorePlayQueue::saveQueue Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 22 11:01:47 volumio-erg volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:47 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPlay Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::play index 0 Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::stop Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::play index undefined Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:01:47 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::startPlaybackTimer Dec 22 11:01:47 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetVisibleSources Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 22 11:01:47 volumio-erg volumio[1166]: info: [1766394107556] ControllerWebradio::clearAddPlayTrack Dec 22 11:01:47 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:01:47 volumio-erg volumio[1166]: info: sendMpdCommand stop took 6 milliseconds Dec 22 11:01:47 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand clear Dec 22 11:01:47 volumio-erg volumio[1166]: info: sendMpdCommand clear took 1 milliseconds Dec 22 11:01:47 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand load "http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3" Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand add "http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3" Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: error: updateQueue error: null Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 409ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 407ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 406ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 405ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 404ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 403ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: sendMpdCommand add "http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3" took 3 milliseconds Dec 22 11:01:47 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 22 11:01:47 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand play Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Dec 22 11:01:47 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:47 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:47 volumio-erg volumio[1166]: info: sendMpdCommand play took 7 milliseconds Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 7ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 6ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 5ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 5ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 4ms Dec 22 11:01:47 volumio-erg volumio[1166]: info: ------------------------------ 3ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 68 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 67 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 67 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 66 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 63 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 62 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 27 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 29ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 25 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 24 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 22 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 20 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 19 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 17 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 16 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 15 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus stop Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":975,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 206ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 274ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 275ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 274ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 273ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 272ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces system playlist update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Ignoring MPD Status Update Dec 22 11:01:48 volumio-erg volumio[1166]: info: Dec 22 11:01:48 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 254 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 253 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 250 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 249 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 249 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 250 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 249 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 72ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 70 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 20ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 17 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 16ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 13 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 12ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 10 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 6ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: sendMpdCommand status took 5 milliseconds Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1204,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:48 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:48 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:48 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:48 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 426ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 425ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 417ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 416ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 416ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 416ms Dec 22 11:01:48 volumio-erg volumio[1166]: info: ------------------------------ 415ms Dec 22 11:01:49 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 395 milliseconds Dec 22 11:01:49 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 396 milliseconds Dec 22 11:01:49 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 396 milliseconds Dec 22 11:01:49 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 396 milliseconds Dec 22 11:01:49 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 396 milliseconds Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:01:49 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:49 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:49 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:49 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:49 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1594,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"TOOTS THIELEMANS - Bluesette…","artist":null,"album":null,"uri":"http://jazz-wr01.ice.infomaniak.ch/jazz-wr01-128.mp3","trackType":"mp3"} Dec 22 11:01:49 volumio-erg volumio[1166]: verbose: CURRENT POSITION 0 Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState stateService play Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::syncState currentStatus play Dec 22 11:01:49 volumio-erg volumio[1166]: info: Received an update from plugin. extracting info from payload Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:01:49 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:49 volumio-erg volumio[1166]: info: ------------------------------ 553ms Dec 22 11:01:49 volumio-erg volumio[1166]: info: ------------------------------ 500ms Dec 22 11:01:49 volumio-erg volumio[1166]: info: ------------------------------ 499ms Dec 22 11:01:49 volumio-erg volumio[1166]: info: ------------------------------ 494ms Dec 22 11:01:49 volumio-erg volumio[1166]: info: ------------------------------ 490ms Dec 22 11:01:49 volumio-erg volumio[1166]: error: touch_display: Error waking up the screen: Error: Command failed: /usr/bin/xset -display :0 s reset dpms force on Dec 22 11:01:49 volumio-erg volumio[1166]: /usr/bin/xset: unable to open display ":0" Dec 22 11:01:49 volumio-erg volumio[1166]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Dec 22 11:01:49 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 22 11:01:58 volumio-erg volumio[1166]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 22 11:01:58 volumio-erg volumio[1166]: info: Received Get System Version Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 22 11:01:58 volumio-erg volumio[1166]: info: Received Get System Info Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 22 11:01:58 volumio-erg volumio[1166]: info: Discovery: Getting this device information Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:01:58 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioStop Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::stop Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::stPlaybackTimer Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::updateTrackBlock Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrackBlock Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::serviceStop Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::serviceStop Dec 22 11:02:02 volumio-erg volumio[1166]: info: [1766394122160] ControllerWebradio::stop Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand stop Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand stop took 113 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: info: Dec 22 11:02:02 volumio-erg volumio[1166]: ---------------------------- MPD announces state update: player Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::getState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand status Dec 22 11:02:02 volumio-erg volumio[1166]: error: touch_display: Error setting screensaver timeout: Error: ENOENT: no such file or directory, stat '/tmp/.X11-unix/X0' Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 18 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 17 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 13 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 11 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 10 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand status took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseState Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: verbose: ControllerMpd::parseTrackInfo Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ControllerMpd::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::servicePushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreStateMachine::pushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioPushState Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output update for this device Dec 22 11:02:02 volumio-erg volumio[1166]: info: MRS: Pushing multiroomSync output Dec 22 11:02:02 volumio-erg volumio[1166]: info: CoreCommandRouter::volumioGetState Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: CorePlayQueue::getTrack 0 Dec 22 11:02:02 volumio-erg volumio[1166]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 116ms Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 114ms Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 114ms Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 110ms Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 109ms Dec 22 11:02:02 volumio-erg volumio[1166]: info: ------------------------------ 108ms Dec 22 11:02:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 22 11:02:08 volumio-erg volumio[1166]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 22 11:02:12 volumio-erg sudo[8437]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 22 11:02:12 volumio-erg sudo[8437]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:02:12 volumio-erg sudo[8439]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 22 11:02:12 volumio-erg sudo[8439]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:02:12 volumio-erg sudo[8437]: pam_unix(sudo:session): session closed for user root Dec 22 11:02:12 volumio-erg sudo[8441]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 22 11:02:12 volumio-erg sudo[8441]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:02:12 volumio-erg volumio[1166]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 22 11:02:12 volumio-erg sudo[8445]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 22 11:02:12 volumio-erg sudo[8445]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:02:12 volumio-erg volumio[1166]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:02:12 volumio-erg volumio[1166]: TypeError: Cannot read properties of undefined (reading 'split') Dec 22 11:02:12 volumio-erg volumio[1166]: at /data/plugins/user_interface/touch_display/index.js:1298:37 Dec 22 11:02:12 volumio-erg volumio[1166]: at ChildProcess.exithandler (node:child_process:413:7) Dec 22 11:02:12 volumio-erg volumio[1166]: at ChildProcess.emit (node:events:514:28) Dec 22 11:02:12 volumio-erg volumio[1166]: at maybeClose (node:internal/child_process:1105:16) Dec 22 11:02:12 volumio-erg volumio[1166]: at Socket. (node:internal/child_process:457:11) Dec 22 11:02:12 volumio-erg volumio[1166]: at Socket.emit (node:events:514:28) Dec 22 11:02:12 volumio-erg volumio[1166]: at Pipe. (node:net:337:12) Dec 22 11:02:12 volumio-erg volumio[1166]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 22 11:02:12 volumio-erg systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 22 11:02:12 volumio-erg sudo[8439]: pam_unix(sudo:session): session closed for user root Dec 22 11:02:13 volumio-erg systemd[1]: Reloading. Dec 22 11:02:16 volumio-erg sudo[8482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-22 11:01' Dec 22 11:02:16 volumio-erg sudo[8482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 22 11:02:16 volumio-erg 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. Dec 22 11:02:16 volumio-erg 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. Dec 22 11:02:16 volumio-erg sudo[8441]: pam_unix(sudo:session): session closed for user root Dec 22 11:02:16 volumio-erg sudo[8445]: pam_unix(sudo:session): session closed for user root 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"