-- Logs begin at Sun 2025-09-14 18:29:27 CEST, end at Sun 2025-09-14 18:48:44 CEST. -- Sep 14 18:47:00 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:00 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 52. Sep 14 18:47:00 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:00 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:00 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:00 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:00 localhost qobuz-connect[5700]: 20250914 18:47:00.146 [5700.5700] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:00 localhost qobuz-connect[5700]: 20250914 18:47:00.148 [5700.5700] INFO VolumeManager: [0x563cdf753700]: Setting new playback volume: 75 Sep 14 18:47:00 localhost qobuz-connect[5700]: 20250914 18:47:00.148 [5700.5700] INFO VolumeManager: [0x563cdf753700]: Setting new mute state: 0 Sep 14 18:47:00 localhost qobuz-connect[5700]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:00 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:00 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:05 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:05 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 53. Sep 14 18:47:05 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:05 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:05 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:05 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:05 localhost qobuz-connect[5703]: 20250914 18:47:05.378 [5703.5703] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:05 localhost qobuz-connect[5703]: 20250914 18:47:05.380 [5703.5703] INFO VolumeManager: [0x55d4172da700]: Setting new playback volume: 75 Sep 14 18:47:05 localhost qobuz-connect[5703]: 20250914 18:47:05.380 [5703.5703] INFO VolumeManager: [0x55d4172da700]: Setting new mute state: 0 Sep 14 18:47:05 localhost qobuz-connect[5703]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:05 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:05 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:10 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:10 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 54. Sep 14 18:47:10 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:10 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:10 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:10 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:10 localhost qobuz-connect[5722]: 20250914 18:47:10.642 [5722.5722] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:10 localhost qobuz-connect[5722]: 20250914 18:47:10.644 [5722.5722] INFO VolumeManager: [0x557cef9fc700]: Setting new playback volume: 75 Sep 14 18:47:10 localhost qobuz-connect[5722]: 20250914 18:47:10.644 [5722.5722] INFO VolumeManager: [0x557cef9fc700]: Setting new mute state: 0 Sep 14 18:47:10 localhost qobuz-connect[5722]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:10 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:10 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:15 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:15 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 55. Sep 14 18:47:15 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:15 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:15 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:15 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:15 localhost qobuz-connect[5724]: 20250914 18:47:15.879 [5724.5724] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:15 localhost qobuz-connect[5724]: 20250914 18:47:15.880 [5724.5724] INFO VolumeManager: [0x55fdf0330700]: Setting new playback volume: 75 Sep 14 18:47:15 localhost qobuz-connect[5724]: 20250914 18:47:15.880 [5724.5724] INFO VolumeManager: [0x55fdf0330700]: Setting new mute state: 0 Sep 14 18:47:15 localhost qobuz-connect[5724]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:15 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:15 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:21 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:21 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 56. Sep 14 18:47:21 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:21 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:21 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:21 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:21 localhost qobuz-connect[5740]: 20250914 18:47:21.130 [5740.5740] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:21 localhost qobuz-connect[5740]: 20250914 18:47:21.132 [5740.5740] INFO VolumeManager: [0x559857c96700]: Setting new playback volume: 75 Sep 14 18:47:21 localhost qobuz-connect[5740]: 20250914 18:47:21.132 [5740.5740] INFO VolumeManager: [0x559857c96700]: Setting new mute state: 0 Sep 14 18:47:21 localhost qobuz-connect[5740]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:21 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:21 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:26 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:26 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 57. Sep 14 18:47:26 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:26 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:26 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:26 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:26 localhost qobuz-connect[5743]: 20250914 18:47:26.386 [5743.5743] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:26 localhost qobuz-connect[5743]: 20250914 18:47:26.388 [5743.5743] INFO VolumeManager: [0x55e730395700]: Setting new playback volume: 75 Sep 14 18:47:26 localhost qobuz-connect[5743]: 20250914 18:47:26.388 [5743.5743] INFO VolumeManager: [0x55e730395700]: Setting new mute state: 0 Sep 14 18:47:26 localhost qobuz-connect[5743]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:26 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:26 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:29 localhost volumiologrotate[852]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 14 18:47:29 localhost volumiologrotate[852]: ls: cannot access 'ST.': No such file or directory Sep 14 18:47:31 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:31 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 58. Sep 14 18:47:31 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:31 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:31 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:31 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:31 localhost qobuz-connect[5809]: 20250914 18:47:31.626 [5809.5809] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:31 localhost qobuz-connect[5809]: 20250914 18:47:31.628 [5809.5809] INFO VolumeManager: [0x56120a758700]: Setting new playback volume: 75 Sep 14 18:47:31 localhost qobuz-connect[5809]: 20250914 18:47:31.628 [5809.5809] INFO VolumeManager: [0x56120a758700]: Setting new mute state: 0 Sep 14 18:47:31 localhost qobuz-connect[5809]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:31 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:31 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:36 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:36 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 59. Sep 14 18:47:36 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:36 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:36 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:36 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:36 localhost qobuz-connect[5811]: 20250914 18:47:36.874 [5811.5811] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:36 localhost qobuz-connect[5811]: 20250914 18:47:36.876 [5811.5811] INFO VolumeManager: [0x55c577f45700]: Setting new playback volume: 75 Sep 14 18:47:36 localhost qobuz-connect[5811]: 20250914 18:47:36.876 [5811.5811] INFO VolumeManager: [0x55c577f45700]: Setting new mute state: 0 Sep 14 18:47:36 localhost qobuz-connect[5811]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:36 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:36 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:42 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:42 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 60. Sep 14 18:47:42 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:42 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:42 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:42 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:42 localhost qobuz-connect[5828]: 20250914 18:47:42.134 [5828.5828] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:42 localhost qobuz-connect[5828]: 20250914 18:47:42.135 [5828.5828] INFO VolumeManager: [0x56039d302700]: Setting new playback volume: 75 Sep 14 18:47:42 localhost qobuz-connect[5828]: 20250914 18:47:42.135 [5828.5828] INFO VolumeManager: [0x56039d302700]: Setting new mute state: 0 Sep 14 18:47:42 localhost qobuz-connect[5828]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:42 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:42 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:47 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:47 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 61. Sep 14 18:47:47 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:47 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:47 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:47 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:47 localhost qobuz-connect[5845]: 20250914 18:47:47.383 [5845.5845] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:47 localhost qobuz-connect[5845]: 20250914 18:47:47.385 [5845.5845] INFO VolumeManager: [0x55cbcf006700]: Setting new playback volume: 75 Sep 14 18:47:47 localhost qobuz-connect[5845]: 20250914 18:47:47.385 [5845.5845] INFO VolumeManager: [0x55cbcf006700]: Setting new mute state: 0 Sep 14 18:47:47 localhost qobuz-connect[5845]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:47 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:47 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:52 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:52 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 62. Sep 14 18:47:52 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:52 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:52 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:52 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:52 localhost qobuz-connect[5853]: 20250914 18:47:52.634 [5853.5853] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:52 localhost qobuz-connect[5853]: 20250914 18:47:52.636 [5853.5853] INFO VolumeManager: [0x5557ec731700]: Setting new playback volume: 75 Sep 14 18:47:52 localhost qobuz-connect[5853]: 20250914 18:47:52.636 [5853.5853] INFO VolumeManager: [0x5557ec731700]: Setting new mute state: 0 Sep 14 18:47:52 localhost qobuz-connect[5853]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:52 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:52 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:57 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:47:57 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 63. Sep 14 18:47:57 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:47:57 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:47:57 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:47:57 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:47:57 localhost qobuz-connect[5869]: 20250914 18:47:57.878 [5869.5869] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:47:57 localhost qobuz-connect[5869]: 20250914 18:47:57.880 [5869.5869] INFO VolumeManager: [0x556c45d3e700]: Setting new playback volume: 75 Sep 14 18:47:57 localhost qobuz-connect[5869]: 20250914 18:47:57.880 [5869.5869] INFO VolumeManager: [0x556c45d3e700]: Setting new mute state: 0 Sep 14 18:47:57 localhost qobuz-connect[5869]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:47:57 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:47:57 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:47:59 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:47:59 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:47:59 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:47:59 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:47:59 localhost volumio[1356]: verbose: New Socket.io Connection to 192.168.0.135:3000 from 192.168.0.104 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:47:59 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 14 18:47:59 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 14 18:48:00 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:00 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:00 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:00 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:00 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:00 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:00 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:00 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:01 localhost sudo[5872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:48:01 localhost sudo[5872]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:48:01 localhost sudo[5872]: pam_unix(sudo:session): session closed for user root Sep 14 18:48:01 localhost sudo[5875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:48:01 localhost sudo[5875]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:48:01 localhost sudo[5875]: pam_unix(sudo:session): session closed for user root Sep 14 18:48:01 localhost volumio[1356]: verbose: New Socket.io Connection to 192.168.0.135 from 192.168.0.104 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: 7 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 18:48:01 localhost volumio[1356]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 14 18:48:01 localhost volumio[1356]: info: Received Get System Info Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:01 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: Listing playlists Sep 14 18:48:01 localhost sudo[5878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 14 18:48:01 localhost sudo[5878]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:48:01 localhost sudo[5878]: pam_unix(sudo:session): session closed for user root Sep 14 18:48:01 localhost sudo[5881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 14 18:48:01 localhost sudo[5881]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:48:01 localhost sudo[5881]: pam_unix(sudo:session): session closed for user root Sep 14 18:48:01 localhost volumio[1356]: verbose: New Socket.io Connection to 192.168.0.135 from 192.168.0.104 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: 7 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 14 18:48:01 localhost volumio[1356]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 14 18:48:01 localhost volumio[1356]: info: Received Get System Info Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:01 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:01 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:01 localhost volumio[1356]: info: Listing playlists Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 18:48:01 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 14 18:48:02 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 14 18:48:03 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:03 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 64. Sep 14 18:48:03 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:03 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:03 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:03 localhost qobuz-connect[5883]: 20250914 18:48:03.139 [5883.5883] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:03 localhost qobuz-connect[5883]: 20250914 18:48:03.141 [5883.5883] INFO VolumeManager: [0x562a97f93700]: Setting new playback volume: 75 Sep 14 18:48:03 localhost qobuz-connect[5883]: 20250914 18:48:03.141 [5883.5883] INFO VolumeManager: [0x562a97f93700]: Setting new mute state: 0 Sep 14 18:48:03 localhost qobuz-connect[5883]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:03 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:03 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 18:48:03 localhost volumio[1356]: info: Received Get System Info Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:03 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:03 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:03 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:05 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 14 18:48:05 localhost volumio[1356]: info: Received Get System Info Sep 14 18:48:05 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:05 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:05 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:05 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:05 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:05 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:08 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:08 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 65. Sep 14 18:48:08 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:08 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:08 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:08 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:08 localhost qobuz-connect[5900]: 20250914 18:48:08.382 [5900.5900] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:08 localhost qobuz-connect[5900]: 20250914 18:48:08.384 [5900.5900] INFO VolumeManager: [0x555c66620700]: Setting new playback volume: 75 Sep 14 18:48:08 localhost qobuz-connect[5900]: 20250914 18:48:08.384 [5900.5900] INFO VolumeManager: [0x555c66620700]: Setting new mute state: 0 Sep 14 18:48:08 localhost qobuz-connect[5900]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:08 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:08 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:10 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:10 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:10 localhost volumio[1356]: info: [1757868490780] ControllerTidal::clearAddPlayTrack Sep 14 18:48:10 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:10 localhost volumio[1356]: info: Sep 14 18:48:10 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:10 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/63879625&soundQuality=LOSSLESS" Sep 14 18:48:10 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:10 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:10 localhost volumio[1356]: info: Sep 14 18:48:10 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:10 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/63879625&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:10 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:10 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/63879625&soundQuality=LOSSLESS Sep 14 18:48:10 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:10 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:10 localhost volumio[1356]: info: Sep 14 18:48:10 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:10 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:10 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:10 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:10 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:10 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:10 localhost volumio[1356]: info: CorePlayQueue::getTrack 0 Sep 14 18:48:10 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63879625&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/63879625&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:10 localhost volumio[1356]: verbose: CURRENT POSITION 0 Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:10 localhost volumio[1356]: info: No code Sep 14 18:48:10 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:10 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:10 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:10 localhost volumio[1356]: info: ------------------------------ 10ms Sep 14 18:48:10 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:10 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 14 18:48:11 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::play index 1 Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: info: [1757868491792] ControllerTidal::clearAddPlayTrack Sep 14 18:48:11 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:11 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS" Sep 14 18:48:11 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:11 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/123949671&soundQuality=LOSSLESS Sep 14 18:48:11 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:11 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:11 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:11 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:11 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"123949671&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:11 localhost volumio[1356]: verbose: CURRENT POSITION 1 Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: No code Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 11ms Sep 14 18:48:11 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:11 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: info: [1757868491963] ControllerTidal::clearAddPlayTrack Sep 14 18:48:11 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:11 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS" Sep 14 18:48:11 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:11 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:11 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/123949671&soundQuality=LOSSLESS Sep 14 18:48:11 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:11 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:11 localhost volumio[1356]: info: Sep 14 18:48:11 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:11 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:11 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:11 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:11 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:11 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: CorePlayQueue::getTrack 1 Sep 14 18:48:11 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"123949671&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/123949671&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:11 localhost volumio[1356]: verbose: CURRENT POSITION 1 Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: No code Sep 14 18:48:11 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:11 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:11 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:11 localhost volumio[1356]: info: ------------------------------ 11ms Sep 14 18:48:11 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:11 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:12 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::play index 2 Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:12 localhost volumio[1356]: info: CorePlayQueue::getTrack 2 Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:12 localhost volumio[1356]: info: CorePlayQueue::getTrack 2 Sep 14 18:48:12 localhost volumio[1356]: info: [1757868492978] ControllerTidal::clearAddPlayTrack Sep 14 18:48:12 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/100202029&soundQuality=LOSSLESS" Sep 14 18:48:12 localhost volumio[1356]: info: Sep 14 18:48:12 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:12 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:12 localhost volumio[1356]: info: Sep 14 18:48:12 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:12 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/100202029&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:12 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:12 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:12 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/100202029&soundQuality=LOSSLESS Sep 14 18:48:12 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:12 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:12 localhost volumio[1356]: info: Sep 14 18:48:12 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:12 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:12 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:12 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:12 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:12 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:12 localhost volumio[1356]: info: CorePlayQueue::getTrack 2 Sep 14 18:48:12 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"100202029&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/100202029&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:12 localhost volumio[1356]: verbose: CURRENT POSITION 2 Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:12 localhost volumio[1356]: info: No code Sep 14 18:48:12 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:12 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:12 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:12 localhost volumio[1356]: info: ------------------------------ 12ms Sep 14 18:48:13 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:13 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:13 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:13 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 66. Sep 14 18:48:13 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:13 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:13 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:13 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:13 localhost qobuz-connect[5907]: 20250914 18:48:13.630 [5907.5907] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:13 localhost qobuz-connect[5907]: 20250914 18:48:13.632 [5907.5907] INFO VolumeManager: [0x559fc0f84700]: Setting new playback volume: 75 Sep 14 18:48:13 localhost qobuz-connect[5907]: 20250914 18:48:13.632 [5907.5907] INFO VolumeManager: [0x559fc0f84700]: Setting new mute state: 0 Sep 14 18:48:13 localhost qobuz-connect[5907]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:13 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:13 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:13 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:13 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::play index 3 Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:13 localhost volumio[1356]: info: CorePlayQueue::getTrack 3 Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:13 localhost volumio[1356]: info: CorePlayQueue::getTrack 3 Sep 14 18:48:13 localhost volumio[1356]: info: [1757868493990] ControllerTidal::clearAddPlayTrack Sep 14 18:48:13 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:13 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:13 localhost volumio[1356]: info: Sep 14 18:48:13 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:13 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:13 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/20330181&soundQuality=LOSSLESS" Sep 14 18:48:13 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:13 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:13 localhost volumio[1356]: info: Sep 14 18:48:13 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:13 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:13 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/20330181&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:13 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:13 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:13 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:13 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/20330181&soundQuality=LOSSLESS Sep 14 18:48:13 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:13 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:13 localhost volumio[1356]: info: Sep 14 18:48:13 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:13 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:13 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:13 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:13 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:14 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:14 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:14 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:14 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:14 localhost volumio[1356]: info: CorePlayQueue::getTrack 3 Sep 14 18:48:14 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"20330181&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/20330181&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:14 localhost volumio[1356]: verbose: CURRENT POSITION 3 Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:14 localhost volumio[1356]: info: No code Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:14 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:14 localhost volumio[1356]: info: ------------------------------ 11ms Sep 14 18:48:14 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:14 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:14 localhost volumio[1356]: info: CoreCommandRouter::volumioGetQueue Sep 14 18:48:14 localhost volumio[1356]: info: CoreStateMachine::getQueue Sep 14 18:48:14 localhost volumio[1356]: info: CorePlayQueue::getQueue Sep 14 18:48:15 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::play index 4 Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:15 localhost volumio[1356]: info: CorePlayQueue::getTrack 4 Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:15 localhost volumio[1356]: info: CorePlayQueue::getTrack 4 Sep 14 18:48:15 localhost volumio[1356]: info: [1757868495000] ControllerTidal::clearAddPlayTrack Sep 14 18:48:15 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:15 localhost volumio[1356]: info: Sep 14 18:48:15 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:15 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/105495890&soundQuality=LOSSLESS" Sep 14 18:48:15 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:15 localhost volumio[1356]: info: Sep 14 18:48:15 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:15 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:15 localhost volumio[1356]: info: ------------------------------ 2ms Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/105495890&soundQuality=LOSSLESS" took 3 milliseconds Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:15 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:15 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/105495890&soundQuality=LOSSLESS Sep 14 18:48:15 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:15 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:15 localhost volumio[1356]: info: Sep 14 18:48:15 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:15 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:15 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:15 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:15 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:15 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:15 localhost volumio[1356]: info: CorePlayQueue::getTrack 4 Sep 14 18:48:15 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"105495890&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/105495890&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:15 localhost volumio[1356]: verbose: CURRENT POSITION 4 Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:15 localhost volumio[1356]: info: No code Sep 14 18:48:15 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:15 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:15 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:15 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:15 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:15 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:16 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::play index 5 Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:16 localhost volumio[1356]: info: CorePlayQueue::getTrack 5 Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:16 localhost volumio[1356]: info: CorePlayQueue::getTrack 5 Sep 14 18:48:16 localhost volumio[1356]: info: [1757868496013] ControllerTidal::clearAddPlayTrack Sep 14 18:48:16 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:16 localhost volumio[1356]: info: Sep 14 18:48:16 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:16 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24054694&soundQuality=LOSSLESS" Sep 14 18:48:16 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:16 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:16 localhost volumio[1356]: info: Sep 14 18:48:16 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:16 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/24054694&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:16 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:16 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/24054694&soundQuality=LOSSLESS Sep 14 18:48:16 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:16 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:16 localhost volumio[1356]: info: Sep 14 18:48:16 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:16 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:16 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:16 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:16 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:16 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:16 localhost volumio[1356]: info: CorePlayQueue::getTrack 5 Sep 14 18:48:16 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"24054694&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/24054694&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:16 localhost volumio[1356]: verbose: CURRENT POSITION 5 Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:16 localhost volumio[1356]: info: No code Sep 14 18:48:16 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:16 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:16 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:16 localhost volumio[1356]: info: ------------------------------ 10ms Sep 14 18:48:16 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:16 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:17 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::play index 6 Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:17 localhost volumio[1356]: info: CorePlayQueue::getTrack 6 Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:17 localhost volumio[1356]: info: CorePlayQueue::getTrack 6 Sep 14 18:48:17 localhost volumio[1356]: info: [1757868497023] ControllerTidal::clearAddPlayTrack Sep 14 18:48:17 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:17 localhost volumio[1356]: info: Sep 14 18:48:17 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:17 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44492257&soundQuality=LOSSLESS" Sep 14 18:48:17 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:17 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/44492257&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:17 localhost volumio[1356]: info: Sep 14 18:48:17 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:17 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:17 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:17 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/44492257&soundQuality=LOSSLESS Sep 14 18:48:17 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:17 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:17 localhost volumio[1356]: info: Sep 14 18:48:17 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:17 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:17 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:17 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:17 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:17 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:17 localhost volumio[1356]: info: CorePlayQueue::getTrack 6 Sep 14 18:48:17 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"44492257&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/44492257&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:17 localhost volumio[1356]: verbose: CURRENT POSITION 6 Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:17 localhost volumio[1356]: info: No code Sep 14 18:48:17 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:17 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:17 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:17 localhost volumio[1356]: info: ------------------------------ 14ms Sep 14 18:48:17 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:18 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::play index 7 Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:18 localhost volumio[1356]: info: CorePlayQueue::getTrack 7 Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:18 localhost volumio[1356]: info: CorePlayQueue::getTrack 7 Sep 14 18:48:18 localhost volumio[1356]: info: [1757868498032] ControllerTidal::clearAddPlayTrack Sep 14 18:48:18 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/25558097&soundQuality=LOSSLESS" Sep 14 18:48:18 localhost volumio[1356]: info: Sep 14 18:48:18 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:18 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:18 localhost volumio[1356]: info: Sep 14 18:48:18 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:18 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/25558097&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:18 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:18 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:18 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/25558097&soundQuality=LOSSLESS Sep 14 18:48:18 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:18 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:18 localhost volumio[1356]: info: Sep 14 18:48:18 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:18 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:18 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:18 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:18 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:18 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:18 localhost volumio[1356]: info: CorePlayQueue::getTrack 7 Sep 14 18:48:18 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"25558097&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/25558097&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:18 localhost volumio[1356]: verbose: CURRENT POSITION 7 Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:18 localhost volumio[1356]: info: No code Sep 14 18:48:18 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:18 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:18 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:18 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:18 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:18 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:18 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 67. Sep 14 18:48:18 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:18 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:18 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:18 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:18 localhost qobuz-connect[5924]: 20250914 18:48:18.882 [5924.5924] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:18 localhost qobuz-connect[5924]: 20250914 18:48:18.884 [5924.5924] INFO VolumeManager: [0x55f946d9b700]: Setting new playback volume: 75 Sep 14 18:48:18 localhost qobuz-connect[5924]: 20250914 18:48:18.884 [5924.5924] INFO VolumeManager: [0x55f946d9b700]: Setting new mute state: 0 Sep 14 18:48:18 localhost qobuz-connect[5924]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:18 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:18 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:19 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::play index 8 Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:19 localhost volumio[1356]: info: CorePlayQueue::getTrack 8 Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:19 localhost volumio[1356]: info: CorePlayQueue::getTrack 8 Sep 14 18:48:19 localhost volumio[1356]: info: [1757868499042] ControllerTidal::clearAddPlayTrack Sep 14 18:48:19 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:19 localhost volumio[1356]: info: Sep 14 18:48:19 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:19 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/82811590&soundQuality=LOSSLESS" Sep 14 18:48:19 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:19 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:19 localhost volumio[1356]: info: Sep 14 18:48:19 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:19 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/82811590&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:19 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:19 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/82811590&soundQuality=LOSSLESS Sep 14 18:48:19 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:19 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:19 localhost volumio[1356]: info: Sep 14 18:48:19 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:19 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:19 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:19 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:19 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:19 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:19 localhost volumio[1356]: info: CorePlayQueue::getTrack 8 Sep 14 18:48:19 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"82811590&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/82811590&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:19 localhost volumio[1356]: verbose: CURRENT POSITION 8 Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:19 localhost volumio[1356]: info: No code Sep 14 18:48:19 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:19 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:19 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:19 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:19 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 14 18:48:19 localhost volumio[1356]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 14 18:48:19 localhost volumio[1356]: info: Received Get System Version Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 14 18:48:19 localhost volumio[1356]: info: Received Get System Info Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:19 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:19 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:20 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::play index 9 Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:20 localhost volumio[1356]: info: CorePlayQueue::getTrack 9 Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:20 localhost volumio[1356]: info: CorePlayQueue::getTrack 9 Sep 14 18:48:20 localhost volumio[1356]: info: [1757868500052] ControllerTidal::clearAddPlayTrack Sep 14 18:48:20 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:20 localhost volumio[1356]: info: Sep 14 18:48:20 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:20 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240198258&soundQuality=LOSSLESS" Sep 14 18:48:20 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:20 localhost volumio[1356]: info: Sep 14 18:48:20 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:20 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:20 localhost volumio[1356]: info: ------------------------------ 2ms Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/240198258&soundQuality=LOSSLESS" took 2 milliseconds Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:20 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:20 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/240198258&soundQuality=LOSSLESS Sep 14 18:48:20 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:20 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:20 localhost volumio[1356]: info: Sep 14 18:48:20 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:20 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:20 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:20 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:20 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:20 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:20 localhost volumio[1356]: info: CorePlayQueue::getTrack 9 Sep 14 18:48:20 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"240198258&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/240198258&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:20 localhost volumio[1356]: verbose: CURRENT POSITION 9 Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:20 localhost volumio[1356]: info: No code Sep 14 18:48:20 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:20 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:20 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:20 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:20 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:20 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:21 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::play index 10 Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:21 localhost volumio[1356]: info: CorePlayQueue::getTrack 10 Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:21 localhost volumio[1356]: info: CorePlayQueue::getTrack 10 Sep 14 18:48:21 localhost volumio[1356]: info: [1757868501063] ControllerTidal::clearAddPlayTrack Sep 14 18:48:21 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:21 localhost volumio[1356]: info: Sep 14 18:48:21 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:21 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/74606230&soundQuality=LOSSLESS" Sep 14 18:48:21 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:21 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:21 localhost volumio[1356]: info: Sep 14 18:48:21 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:21 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/74606230&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:21 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:21 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/74606230&soundQuality=LOSSLESS Sep 14 18:48:21 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:21 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:21 localhost volumio[1356]: info: Sep 14 18:48:21 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:21 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:21 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:21 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:21 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:21 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:21 localhost volumio[1356]: info: CorePlayQueue::getTrack 10 Sep 14 18:48:21 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"74606230&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/74606230&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:21 localhost volumio[1356]: verbose: CURRENT POSITION 10 Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:21 localhost volumio[1356]: info: No code Sep 14 18:48:21 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:21 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:21 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:21 localhost volumio[1356]: info: ------------------------------ 11ms Sep 14 18:48:21 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:21 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:22 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::play index 11 Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:22 localhost volumio[1356]: info: CorePlayQueue::getTrack 11 Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:22 localhost volumio[1356]: info: CorePlayQueue::getTrack 11 Sep 14 18:48:22 localhost volumio[1356]: info: [1757868502075] ControllerTidal::clearAddPlayTrack Sep 14 18:48:22 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:22 localhost volumio[1356]: info: Sep 14 18:48:22 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:22 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/425972888&soundQuality=LOSSLESS" Sep 14 18:48:22 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:22 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:22 localhost volumio[1356]: info: Sep 14 18:48:22 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:22 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/425972888&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:22 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:22 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/425972888&soundQuality=LOSSLESS Sep 14 18:48:22 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:22 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:22 localhost volumio[1356]: info: Sep 14 18:48:22 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:22 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:22 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:22 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:22 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:22 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:22 localhost volumio[1356]: info: CorePlayQueue::getTrack 11 Sep 14 18:48:22 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"425972888&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/425972888&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:22 localhost volumio[1356]: verbose: CURRENT POSITION 11 Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:22 localhost volumio[1356]: info: No code Sep 14 18:48:22 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:22 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:22 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:22 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:22 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:22 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:23 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::play index 12 Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:23 localhost volumio[1356]: info: CorePlayQueue::getTrack 12 Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:23 localhost volumio[1356]: info: CorePlayQueue::getTrack 12 Sep 14 18:48:23 localhost volumio[1356]: info: [1757868503085] ControllerTidal::clearAddPlayTrack Sep 14 18:48:23 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:23 localhost volumio[1356]: info: Sep 14 18:48:23 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:23 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/14403645&soundQuality=LOSSLESS" Sep 14 18:48:23 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:23 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:23 localhost volumio[1356]: info: Sep 14 18:48:23 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:23 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/14403645&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:23 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:23 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/14403645&soundQuality=LOSSLESS Sep 14 18:48:23 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:23 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:23 localhost volumio[1356]: info: Sep 14 18:48:23 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:23 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:23 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:23 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:23 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:23 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:23 localhost volumio[1356]: info: CorePlayQueue::getTrack 12 Sep 14 18:48:23 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"14403645&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/14403645&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:23 localhost volumio[1356]: verbose: CURRENT POSITION 12 Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:23 localhost volumio[1356]: info: No code Sep 14 18:48:23 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:23 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:23 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:23 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:23 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:23 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:24 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:24 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 68. Sep 14 18:48:24 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:24 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::play index 13 Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:24 localhost volumio[1356]: info: CorePlayQueue::getTrack 13 Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:24 localhost volumio[1356]: info: CorePlayQueue::getTrack 13 Sep 14 18:48:24 localhost volumio[1356]: info: [1757868504094] ControllerTidal::clearAddPlayTrack Sep 14 18:48:24 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:24 localhost volumio[1356]: info: Sep 14 18:48:24 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:24 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17133870&soundQuality=LOSSLESS" Sep 14 18:48:24 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:24 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:24 localhost volumio[1356]: info: Sep 14 18:48:24 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:24 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17133870&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:24 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:24 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/17133870&soundQuality=LOSSLESS Sep 14 18:48:24 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:24 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:24 localhost volumio[1356]: info: Sep 14 18:48:24 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:24 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:24 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:24 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:24 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:24 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:24 localhost volumio[1356]: info: CorePlayQueue::getTrack 13 Sep 14 18:48:24 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17133870&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17133870&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:24 localhost volumio[1356]: verbose: CURRENT POSITION 13 Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:24 localhost volumio[1356]: info: No code Sep 14 18:48:24 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:24 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:24 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:24 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:24 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:24 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:24 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:24 localhost qobuz-connect[5926]: 20250914 18:48:24.147 [5926.5926] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:24 localhost qobuz-connect[5926]: 20250914 18:48:24.148 [5926.5926] INFO VolumeManager: [0x56188630e700]: Setting new playback volume: 75 Sep 14 18:48:24 localhost qobuz-connect[5926]: 20250914 18:48:24.148 [5926.5926] INFO VolumeManager: [0x56188630e700]: Setting new mute state: 0 Sep 14 18:48:24 localhost qobuz-connect[5926]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:24 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:24 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 14 18:48:24 localhost volumio[1356]: info: Discovery: Getting this device information Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:24 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 14 18:48:25 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::play index 14 Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:25 localhost volumio[1356]: info: CorePlayQueue::getTrack 14 Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:25 localhost volumio[1356]: info: CorePlayQueue::getTrack 14 Sep 14 18:48:25 localhost volumio[1356]: info: [1757868505104] ControllerTidal::clearAddPlayTrack Sep 14 18:48:25 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/45713961&soundQuality=LOSSLESS" Sep 14 18:48:25 localhost volumio[1356]: info: Sep 14 18:48:25 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:25 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:25 localhost volumio[1356]: info: Sep 14 18:48:25 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:25 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/45713961&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:25 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:25 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:25 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/45713961&soundQuality=LOSSLESS Sep 14 18:48:25 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:25 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:25 localhost volumio[1356]: info: Sep 14 18:48:25 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:25 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:25 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:25 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:25 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:25 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:25 localhost volumio[1356]: info: CorePlayQueue::getTrack 14 Sep 14 18:48:25 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"45713961&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/45713961&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:25 localhost volumio[1356]: verbose: CURRENT POSITION 14 Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:25 localhost volumio[1356]: info: No code Sep 14 18:48:25 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:25 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:25 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:25 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:25 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:25 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:26 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::play index 15 Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:26 localhost volumio[1356]: info: CorePlayQueue::getTrack 15 Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:26 localhost volumio[1356]: info: CorePlayQueue::getTrack 15 Sep 14 18:48:26 localhost volumio[1356]: info: [1757868506114] ControllerTidal::clearAddPlayTrack Sep 14 18:48:26 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:26 localhost volumio[1356]: info: Sep 14 18:48:26 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:26 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/40617393&soundQuality=LOSSLESS" Sep 14 18:48:26 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:26 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:26 localhost volumio[1356]: info: Sep 14 18:48:26 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:26 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/40617393&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:26 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:26 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/40617393&soundQuality=LOSSLESS Sep 14 18:48:26 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:26 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:26 localhost volumio[1356]: info: Sep 14 18:48:26 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:26 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:26 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:26 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:26 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:26 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:26 localhost volumio[1356]: info: CorePlayQueue::getTrack 15 Sep 14 18:48:26 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"40617393&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/40617393&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:26 localhost volumio[1356]: verbose: CURRENT POSITION 15 Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:26 localhost volumio[1356]: info: No code Sep 14 18:48:26 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:26 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:26 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:26 localhost volumio[1356]: info: ------------------------------ 10ms Sep 14 18:48:26 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:26 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:27 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::play index 16 Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:27 localhost volumio[1356]: info: CorePlayQueue::getTrack 16 Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:27 localhost volumio[1356]: info: CorePlayQueue::getTrack 16 Sep 14 18:48:27 localhost volumio[1356]: info: [1757868507125] ControllerTidal::clearAddPlayTrack Sep 14 18:48:27 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:27 localhost volumio[1356]: info: Sep 14 18:48:27 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:27 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/222650487&soundQuality=LOSSLESS" Sep 14 18:48:27 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:27 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:27 localhost volumio[1356]: info: Sep 14 18:48:27 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:27 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/222650487&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:27 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:27 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/222650487&soundQuality=LOSSLESS Sep 14 18:48:27 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:27 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:27 localhost volumio[1356]: info: Sep 14 18:48:27 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:27 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:27 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:27 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:27 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:27 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:27 localhost volumio[1356]: info: CorePlayQueue::getTrack 16 Sep 14 18:48:27 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"222650487&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/222650487&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:27 localhost volumio[1356]: verbose: CURRENT POSITION 16 Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:27 localhost volumio[1356]: info: No code Sep 14 18:48:27 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:27 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:27 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:27 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:27 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:27 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:28 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::play index 17 Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:28 localhost volumio[1356]: info: CorePlayQueue::getTrack 17 Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:28 localhost volumio[1356]: info: CorePlayQueue::getTrack 17 Sep 14 18:48:28 localhost volumio[1356]: info: [1757868508135] ControllerTidal::clearAddPlayTrack Sep 14 18:48:28 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/222650489&soundQuality=LOSSLESS" Sep 14 18:48:28 localhost volumio[1356]: info: Sep 14 18:48:28 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:28 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:28 localhost volumio[1356]: info: Sep 14 18:48:28 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:28 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/222650489&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:28 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:28 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:28 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/222650489&soundQuality=LOSSLESS Sep 14 18:48:28 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:28 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:28 localhost volumio[1356]: info: Sep 14 18:48:28 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:28 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:28 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:28 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:28 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:28 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:28 localhost volumio[1356]: info: CorePlayQueue::getTrack 17 Sep 14 18:48:28 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"222650489&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/222650489&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:28 localhost volumio[1356]: verbose: CURRENT POSITION 17 Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:28 localhost volumio[1356]: info: No code Sep 14 18:48:28 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:28 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:28 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:28 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:28 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:28 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:29 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::play index 18 Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:29 localhost volumio[1356]: info: CorePlayQueue::getTrack 18 Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:29 localhost volumio[1356]: info: CorePlayQueue::getTrack 18 Sep 14 18:48:29 localhost volumio[1356]: info: [1757868509144] ControllerTidal::clearAddPlayTrack Sep 14 18:48:29 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:29 localhost volumio[1356]: info: Sep 14 18:48:29 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:29 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/116440528&soundQuality=LOSSLESS" Sep 14 18:48:29 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:29 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:29 localhost volumio[1356]: info: Sep 14 18:48:29 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:29 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/116440528&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:29 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:29 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/116440528&soundQuality=LOSSLESS Sep 14 18:48:29 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:29 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:29 localhost volumio[1356]: info: Sep 14 18:48:29 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:29 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:29 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:29 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:29 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:29 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:29 localhost volumio[1356]: info: CorePlayQueue::getTrack 18 Sep 14 18:48:29 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"116440528&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/116440528&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:29 localhost volumio[1356]: verbose: CURRENT POSITION 18 Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:29 localhost volumio[1356]: info: No code Sep 14 18:48:29 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:29 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:29 localhost volumio[1356]: info: ------------------------------ 10ms Sep 14 18:48:29 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:29 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:29 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:29 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 69. Sep 14 18:48:29 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:29 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:29 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:29 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:29 localhost qobuz-connect[5943]: 20250914 18:48:29.374 [5943.5943] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:29 localhost qobuz-connect[5943]: 20250914 18:48:29.376 [5943.5943] INFO VolumeManager: [0x558ac37c9700]: Setting new playback volume: 75 Sep 14 18:48:29 localhost qobuz-connect[5943]: 20250914 18:48:29.376 [5943.5943] INFO VolumeManager: [0x558ac37c9700]: Setting new mute state: 0 Sep 14 18:48:29 localhost qobuz-connect[5943]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:29 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:29 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:29 localhost volumiologrotate[852]: ls: cannot access '/var/log/samba/log.wb-VOLUMIO': No such file or directory Sep 14 18:48:29 localhost volumiologrotate[852]: ls: cannot access 'ST.': No such file or directory Sep 14 18:48:30 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::play index 19 Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:30 localhost volumio[1356]: info: CorePlayQueue::getTrack 19 Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:30 localhost volumio[1356]: info: CorePlayQueue::getTrack 19 Sep 14 18:48:30 localhost volumio[1356]: info: [1757868510155] ControllerTidal::clearAddPlayTrack Sep 14 18:48:30 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:30 localhost volumio[1356]: info: Sep 14 18:48:30 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:30 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/116440527&soundQuality=LOSSLESS" Sep 14 18:48:30 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:30 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:30 localhost volumio[1356]: info: Sep 14 18:48:30 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:30 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/116440527&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:30 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:30 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/116440527&soundQuality=LOSSLESS Sep 14 18:48:30 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:30 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:30 localhost volumio[1356]: info: Sep 14 18:48:30 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:30 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:30 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:30 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:30 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:30 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:30 localhost volumio[1356]: info: CorePlayQueue::getTrack 19 Sep 14 18:48:30 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"116440527&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/116440527&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:30 localhost volumio[1356]: verbose: CURRENT POSITION 19 Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:30 localhost volumio[1356]: info: No code Sep 14 18:48:30 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:30 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:30 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:30 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:30 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:30 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:31 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::play index 20 Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:31 localhost volumio[1356]: info: CorePlayQueue::getTrack 20 Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:31 localhost volumio[1356]: info: CorePlayQueue::getTrack 20 Sep 14 18:48:31 localhost volumio[1356]: info: [1757868511164] ControllerTidal::clearAddPlayTrack Sep 14 18:48:31 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:31 localhost volumio[1356]: info: Sep 14 18:48:31 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:31 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77168312&soundQuality=LOSSLESS" Sep 14 18:48:31 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:31 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:31 localhost volumio[1356]: info: Sep 14 18:48:31 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:31 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77168312&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:31 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:31 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/77168312&soundQuality=LOSSLESS Sep 14 18:48:31 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:31 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:31 localhost volumio[1356]: info: Sep 14 18:48:31 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:31 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:31 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:31 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:31 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:31 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:31 localhost volumio[1356]: info: CorePlayQueue::getTrack 20 Sep 14 18:48:31 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"77168312&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77168312&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:31 localhost volumio[1356]: verbose: CURRENT POSITION 20 Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:31 localhost volumio[1356]: info: No code Sep 14 18:48:31 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:31 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:31 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:31 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:31 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:31 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:32 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::play index 21 Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:32 localhost volumio[1356]: info: CorePlayQueue::getTrack 21 Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:32 localhost volumio[1356]: info: CorePlayQueue::getTrack 21 Sep 14 18:48:32 localhost volumio[1356]: info: [1757868512174] ControllerTidal::clearAddPlayTrack Sep 14 18:48:32 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:32 localhost volumio[1356]: info: Sep 14 18:48:32 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:32 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77168313&soundQuality=LOSSLESS" Sep 14 18:48:32 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:32 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:32 localhost volumio[1356]: info: Sep 14 18:48:32 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:32 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/77168313&soundQuality=LOSSLESS" took 2 milliseconds Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:32 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:32 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/77168313&soundQuality=LOSSLESS Sep 14 18:48:32 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:32 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:32 localhost volumio[1356]: info: Sep 14 18:48:32 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:32 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:32 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:32 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:32 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:32 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:32 localhost volumio[1356]: info: CorePlayQueue::getTrack 21 Sep 14 18:48:32 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"77168313&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/77168313&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:32 localhost volumio[1356]: verbose: CURRENT POSITION 21 Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:32 localhost volumio[1356]: info: No code Sep 14 18:48:32 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:32 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:32 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:32 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:32 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:32 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:33 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::play index 22 Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:33 localhost volumio[1356]: info: CorePlayQueue::getTrack 22 Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:33 localhost volumio[1356]: info: CorePlayQueue::getTrack 22 Sep 14 18:48:33 localhost volumio[1356]: info: [1757868513186] ControllerTidal::clearAddPlayTrack Sep 14 18:48:33 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:33 localhost volumio[1356]: info: Sep 14 18:48:33 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:33 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/64037008&soundQuality=LOSSLESS" Sep 14 18:48:33 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:33 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:33 localhost volumio[1356]: info: Sep 14 18:48:33 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:33 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/64037008&soundQuality=LOSSLESS" took 0 milliseconds Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:33 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand play took 1 milliseconds Sep 14 18:48:33 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/64037008&soundQuality=LOSSLESS Sep 14 18:48:33 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:33 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:33 localhost volumio[1356]: info: Sep 14 18:48:33 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:33 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:33 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:33 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:33 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:33 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:33 localhost volumio[1356]: info: CorePlayQueue::getTrack 22 Sep 14 18:48:33 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"64037008&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/64037008&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:33 localhost volumio[1356]: verbose: CURRENT POSITION 22 Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:33 localhost volumio[1356]: info: No code Sep 14 18:48:33 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:33 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:33 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:33 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:33 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: Retrieving Cloud Streaming UI Sep 14 18:48:33 localhost volumio[1356]: info: Getting Tidal Cloud Configuration Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: Getting Qobuz Cloud Configuration Sep 14 18:48:33 localhost volumio[1356]: info: Asking plugin for UI Config Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: Getting Spotify Cloud Configuration Sep 14 18:48:33 localhost volumio[1356]: info: Asking plugin for UI Config Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: Saving Spotify Acccount Sep 14 18:48:33 localhost volumio[1356]: info: Got it Sep 14 18:48:33 localhost volumio[1356]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 14 18:48:33 localhost volumio[1356]: info: Got Tidal Cloud Configuration Sep 14 18:48:33 localhost volumio[1356]: info: Got it Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::volumioGetBrowseSources Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 18:48:33 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 14 18:48:34 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::play index 23 Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:34 localhost volumio[1356]: info: CorePlayQueue::getTrack 23 Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:34 localhost volumio[1356]: info: CorePlayQueue::getTrack 23 Sep 14 18:48:34 localhost volumio[1356]: info: [1757868514197] ControllerTidal::clearAddPlayTrack Sep 14 18:48:34 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:34 localhost volumio[1356]: info: Sep 14 18:48:34 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:34 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17010344&soundQuality=LOSSLESS" Sep 14 18:48:34 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:34 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/17010344&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:34 localhost volumio[1356]: info: Sep 14 18:48:34 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:34 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:34 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:34 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/17010344&soundQuality=LOSSLESS Sep 14 18:48:34 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:34 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:34 localhost volumio[1356]: info: Sep 14 18:48:34 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:34 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:34 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:34 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:34 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:34 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:34 localhost volumio[1356]: info: CorePlayQueue::getTrack 23 Sep 14 18:48:34 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"17010344&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17010344&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:34 localhost volumio[1356]: verbose: CURRENT POSITION 23 Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:34 localhost volumio[1356]: info: No code Sep 14 18:48:34 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:34 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:34 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:34 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:34 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:34 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:34 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 70. Sep 14 18:48:34 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:34 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:34 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:34 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:34 localhost qobuz-connect[5995]: 20250914 18:48:34.638 [5995.5995] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:34 localhost qobuz-connect[5995]: 20250914 18:48:34.640 [5995.5995] INFO VolumeManager: [0x55f6424db700]: Setting new playback volume: 75 Sep 14 18:48:34 localhost qobuz-connect[5995]: 20250914 18:48:34.640 [5995.5995] INFO VolumeManager: [0x55f6424db700]: Setting new mute state: 0 Sep 14 18:48:34 localhost qobuz-connect[5995]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:34 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:34 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:35 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::play index 24 Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:35 localhost volumio[1356]: info: CorePlayQueue::getTrack 24 Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:35 localhost volumio[1356]: info: CorePlayQueue::getTrack 24 Sep 14 18:48:35 localhost volumio[1356]: info: [1757868515207] ControllerTidal::clearAddPlayTrack Sep 14 18:48:35 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:35 localhost volumio[1356]: info: Sep 14 18:48:35 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:35 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/114970571&soundQuality=LOSSLESS" Sep 14 18:48:35 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:35 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:35 localhost volumio[1356]: info: Sep 14 18:48:35 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:35 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/114970571&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:35 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:35 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/114970571&soundQuality=LOSSLESS Sep 14 18:48:35 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:35 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:35 localhost volumio[1356]: info: Sep 14 18:48:35 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:35 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:35 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand status took 2 milliseconds Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:35 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:35 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:35 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:35 localhost volumio[1356]: info: CorePlayQueue::getTrack 24 Sep 14 18:48:35 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"114970571&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/114970571&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:35 localhost volumio[1356]: verbose: CURRENT POSITION 24 Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:35 localhost volumio[1356]: info: No code Sep 14 18:48:35 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:35 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:35 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:35 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:35 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:35 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:36 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::play index 25 Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:36 localhost volumio[1356]: info: CorePlayQueue::getTrack 25 Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:36 localhost volumio[1356]: info: CorePlayQueue::getTrack 25 Sep 14 18:48:36 localhost volumio[1356]: info: [1757868516217] ControllerTidal::clearAddPlayTrack Sep 14 18:48:36 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/250755827&soundQuality=LOSSLESS" Sep 14 18:48:36 localhost volumio[1356]: info: Sep 14 18:48:36 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:36 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:36 localhost volumio[1356]: info: Sep 14 18:48:36 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:36 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/250755827&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:36 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:36 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:36 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/250755827&soundQuality=LOSSLESS Sep 14 18:48:36 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:36 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:36 localhost volumio[1356]: info: Sep 14 18:48:36 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:36 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:36 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:36 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:36 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:36 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:36 localhost volumio[1356]: info: CorePlayQueue::getTrack 25 Sep 14 18:48:36 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"250755827&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/250755827&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:36 localhost volumio[1356]: verbose: CURRENT POSITION 25 Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:36 localhost volumio[1356]: info: No code Sep 14 18:48:36 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:36 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:36 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:36 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:36 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:36 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:37 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::play index 26 Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:37 localhost volumio[1356]: info: CorePlayQueue::getTrack 26 Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:37 localhost volumio[1356]: info: CorePlayQueue::getTrack 26 Sep 14 18:48:37 localhost volumio[1356]: info: [1757868517228] ControllerTidal::clearAddPlayTrack Sep 14 18:48:37 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:37 localhost volumio[1356]: info: Sep 14 18:48:37 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:37 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/63707436&soundQuality=LOSSLESS" Sep 14 18:48:37 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:37 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/63707436&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:37 localhost volumio[1356]: info: Sep 14 18:48:37 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:37 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:37 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:37 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/63707436&soundQuality=LOSSLESS Sep 14 18:48:37 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:37 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:37 localhost volumio[1356]: info: Sep 14 18:48:37 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:37 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:37 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:37 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:37 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:37 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:37 localhost volumio[1356]: info: CorePlayQueue::getTrack 26 Sep 14 18:48:37 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"63707436&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/63707436&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:37 localhost volumio[1356]: verbose: CURRENT POSITION 26 Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:37 localhost volumio[1356]: info: No code Sep 14 18:48:37 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:37 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:37 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:37 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:37 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:37 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 18:48:38 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::play index 27 Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:38 localhost volumio[1356]: info: CorePlayQueue::getTrack 27 Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:38 localhost volumio[1356]: info: CorePlayQueue::getTrack 27 Sep 14 18:48:38 localhost volumio[1356]: info: [1757868518237] ControllerTidal::clearAddPlayTrack Sep 14 18:48:38 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:38 localhost volumio[1356]: info: Sep 14 18:48:38 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:38 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand clear took 1 milliseconds Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52013716&soundQuality=LOSSLESS" Sep 14 18:48:38 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:38 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:38 localhost volumio[1356]: info: Sep 14 18:48:38 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:38 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52013716&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:38 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:38 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/52013716&soundQuality=LOSSLESS Sep 14 18:48:38 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:38 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:38 localhost volumio[1356]: info: Sep 14 18:48:38 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:38 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:38 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand clearerror took 0 milliseconds Sep 14 18:48:38 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:38 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:38 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:38 localhost volumio[1356]: info: CorePlayQueue::getTrack 27 Sep 14 18:48:38 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52013716&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52013716&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:38 localhost volumio[1356]: verbose: CURRENT POSITION 27 Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:38 localhost volumio[1356]: info: No code Sep 14 18:48:38 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:38 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:38 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:38 localhost volumio[1356]: info: ------------------------------ 10ms Sep 14 18:48:38 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:38 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:39 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::play index 28 Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:39 localhost volumio[1356]: info: CorePlayQueue::getTrack 28 Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:39 localhost volumio[1356]: info: CorePlayQueue::getTrack 28 Sep 14 18:48:39 localhost volumio[1356]: info: [1757868519247] ControllerTidal::clearAddPlayTrack Sep 14 18:48:39 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand stop took 1 milliseconds Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:39 localhost volumio[1356]: info: Sep 14 18:48:39 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:39 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/57647552&soundQuality=LOSSLESS" Sep 14 18:48:39 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:39 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:39 localhost volumio[1356]: info: Sep 14 18:48:39 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:39 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/57647552&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:39 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:39 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/57647552&soundQuality=LOSSLESS Sep 14 18:48:39 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:39 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:39 localhost volumio[1356]: info: Sep 14 18:48:39 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:39 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:39 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:39 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 1 milliseconds Sep 14 18:48:39 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:39 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:39 localhost volumio[1356]: info: CorePlayQueue::getTrack 28 Sep 14 18:48:39 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"57647552&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/57647552&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:39 localhost volumio[1356]: verbose: CURRENT POSITION 28 Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:39 localhost volumio[1356]: info: No code Sep 14 18:48:39 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:39 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:39 localhost volumio[1356]: info: ------------------------------ 8ms Sep 14 18:48:39 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:39 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:39 localhost systemd[1]: qobuz-connect.service: Service RestartSec=5s expired, scheduling restart. Sep 14 18:48:39 localhost systemd[1]: qobuz-connect.service: Scheduled restart job, restart counter is at 71. Sep 14 18:48:39 localhost systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 14 18:48:39 localhost systemd[1]: Started Volumio Qobuz Connect Service. Sep 14 18:48:39 localhost volumio[1356]: info: Executing endpoint qc_getconfig Sep 14 18:48:39 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 14 18:48:39 localhost qobuz-connect[6013]: 20250914 18:48:39.878 [6013.6013] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 14 18:48:39 localhost qobuz-connect[6013]: 20250914 18:48:39.880 [6013.6013] INFO VolumeManager: [0x563038be1700]: Setting new playback volume: 75 Sep 14 18:48:39 localhost qobuz-connect[6013]: 20250914 18:48:39.880 [6013.6013] INFO VolumeManager: [0x563038be1700]: Setting new mute state: 0 Sep 14 18:48:39 localhost qobuz-connect[6013]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Sep 14 18:48:39 localhost systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Sep 14 18:48:39 localhost systemd[1]: qobuz-connect.service: Failed with result 'signal'. Sep 14 18:48:40 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::play index 29 Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:40 localhost volumio[1356]: info: CorePlayQueue::getTrack 29 Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::startPlaybackTimer Sep 14 18:48:40 localhost volumio[1356]: info: CorePlayQueue::getTrack 29 Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioGetVisibleSources Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 14 18:48:40 localhost volumio[1356]: info: [1757868520257] ControllerTidal::clearAddPlayTrack Sep 14 18:48:40 localhost volumio[1356]: info: Getting stream with soundQuality LOSSLESS Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand stop Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand stop took 0 milliseconds Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clear Sep 14 18:48:40 localhost volumio[1356]: info: Sep 14 18:48:40 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:40 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand clear took 0 milliseconds Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52577956&soundQuality=LOSSLESS" Sep 14 18:48:40 localhost volumio[1356]: error: updateQueue error: null Sep 14 18:48:40 localhost volumio[1356]: info: ------------------------------ 0ms Sep 14 18:48:40 localhost volumio[1356]: info: Sep 14 18:48:40 localhost volumio[1356]: ---------------------------- MPD announces system playlist update Sep 14 18:48:40 localhost volumio[1356]: info: Ignoring MPD Status Update Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/52577956&soundQuality=LOSSLESS" took 1 milliseconds Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand play Sep 14 18:48:40 localhost volumio[1356]: info: ------------------------------ 1ms Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand play took 0 milliseconds Sep 14 18:48:40 localhost volumio[1356]: STREAMING PROXY: Handling url /?data=tidal://song/52577956&soundQuality=LOSSLESS Sep 14 18:48:40 localhost volumio[1356]: info: No valid Plugin REST Endpoint: getStreamUrltidal Sep 14 18:48:40 localhost volumio[1356]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl: {"success":false,"error":"No valid Plugin REST Endpoint: getStreamUrltidal"} Sep 14 18:48:40 localhost volumio[1356]: info: Sep 14 18:48:40 localhost volumio[1356]: ---------------------------- MPD announces state update: player Sep 14 18:48:40 localhost volumio[1356]: info: ControllerMpd::getState Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand status Sep 14 18:48:40 localhost volumio[1356]: error: MPD Playback Error: Failed to decode stream, moving to next track Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand clearerror Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand status took 1 milliseconds Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::parseState Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand clearerror took 1 milliseconds Sep 14 18:48:40 localhost volumio[1356]: info: sendMpdCommand playlistinfo took 0 milliseconds Sep 14 18:48:40 localhost volumio[1356]: verbose: ControllerMpd::parseTrackInfo Sep 14 18:48:40 localhost volumio[1356]: info: ControllerMpd::pushState Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::servicePushState Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:40 localhost volumio[1356]: info: CorePlayQueue::getTrack 29 Sep 14 18:48:40 localhost volumio[1356]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"52577956&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/52577956&soundQuality=LOSSLESS","trackType":"tidal"} Sep 14 18:48:40 localhost volumio[1356]: verbose: CURRENT POSITION 29 Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::syncState stateService stop Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::syncState currentStatus stop Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:40 localhost volumio[1356]: info: No code Sep 14 18:48:40 localhost volumio[1356]: info: CoreStateMachine::pushState Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioPushState Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output update for this device Sep 14 18:48:40 localhost volumio[1356]: info: MRS: Pushing multiroomSync output Sep 14 18:48:40 localhost volumio[1356]: info: CoreCommandRouter::volumioGetState Sep 14 18:48:40 localhost volumio[1356]: info: ------------------------------ 9ms Sep 14 18:48:40 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:40 localhost volumio[1356]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.gNdYdeSj7XYl39kAfRjtiG4gE6h2.9579d90a0b0538397e3043ae8733fb7e.state.status' Sep 14 18:48:41 localhost volumio[1356]: info: Moving to next track after playback error Sep 14 18:48:41 localhost volumio[1356]: info: CoreCommandRouter::volumioPlay Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::play index 30 Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::stop Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::play index undefined Sep 14 18:48:41 localhost volumio[1356]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 14 18:48:41 localhost volumio[1356]: info: CorePlayQueue::getTrack 30 Sep 14 18:48:41 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 14 18:48:42 localhost volumio[1356]: info: Executing endpoint metavolumio Sep 14 18:48:42 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Sep 14 18:48:43 localhost volumio[1356]: info: Disabling MyMusic plugin upnp Sep 14 18:48:43 localhost sudo[6017]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Sep 14 18:48:43 localhost sudo[6017]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 14 18:48:43 localhost systemd[1]: Stopping UPnP Renderer front-end to MPD... Sep 14 18:48:43 localhost volumio[1356]: error: Upnp client error: Error: This socket has been ended by the other party Sep 14 18:48:43 localhost volumio[1356]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 14 18:48:43 localhost volumio[1356]: info: Enabling MyMusic plugin upnp Sep 14 18:48:43 localhost volumio[1356]: info: Enabling plugin upnp Sep 14 18:48:43 localhost volumio[1356]: info: Loading plugin "upnp"... Sep 14 18:48:43 localhost volumio[1356]: info: [1757868523926] Starting Upmpd Daemon Sep 14 18:48:43 localhost volumio[1356]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 14 18:48:43 localhost volumio[1356]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 18:48:43 localhost volumio[1356]: Error: listen EADDRINUSE: address already in use :::6599 Sep 14 18:48:43 localhost volumio[1356]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Sep 14 18:48:43 localhost volumio[1356]: at listenInCluster (net.js:1379:12) Sep 14 18:48:43 localhost volumio[1356]: at Server.listen (net.js:1465:7) Sep 14 18:48:43 localhost volumio[1356]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Sep 14 18:48:43 localhost volumio[1356]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Sep 14 18:48:43 localhost volumio[1356]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Sep 14 18:48:43 localhost volumio[1356]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Sep 14 18:48:43 localhost volumio[1356]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Sep 14 18:48:43 localhost volumio[1356]: code: 'EADDRINUSE', Sep 14 18:48:43 localhost volumio[1356]: errno: -98, Sep 14 18:48:43 localhost volumio[1356]: syscall: 'listen', Sep 14 18:48:43 localhost volumio[1356]: address: '::', Sep 14 18:48:43 localhost volumio[1356]: port: 6599 Sep 14 18:48:43 localhost volumio[1356]: } Sep 14 18:48:43 localhost volumio[1356]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 14 18:48:44 localhost sudo[6028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-14 18:47 Sep 14 18:48:44 localhost sudo[6028]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"