Dec 19 11:38:59 volumio4x systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 11:39:00 volumio4x systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:39:00 volumio4x volumio[1460]: info: Starting Shairport Sync Dec 19 11:39:00 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:00 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:00 volumio4x sudo[7104]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 11:39:00 volumio4x sudo[7104]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:00 volumio4x sudo[7073]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x sudo[7081]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x sudo[7071]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 11:39:00 volumio4x systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 11:39:00 volumio4x systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 11:39:00 volumio4x systemd[1]: shairport-sync.service: Start request repeated too quickly. Dec 19 11:39:00 volumio4x systemd[1]: shairport-sync.service: Failed with result 'start-limit-hit'. Dec 19 11:39:00 volumio4x systemd[1]: Failed to start shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 11:39:00 volumio4x sudo[7107]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 19 11:39:00 volumio4x sudo[7107]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:00 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:00 volumio4x sudo[7104]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x volumio[1460]: info: Shairport-sync error: Error: Command failed: /usr/bin/sudo /bin/systemctl restart shairport-sync Dec 19 11:39:00 volumio4x volumio[1460]: Job for shairport-sync.service failed because start of the service was attempted too often. Dec 19 11:39:00 volumio4x volumio[1460]: See "systemctl status shairport-sync.service" and "journalctl -xeu shairport-sync.service" for details. Dec 19 11:39:00 volumio4x volumio[1460]: To force a start use "systemctl reset-failed shairport-sync.service" Dec 19 11:39:00 volumio4x volumio[1460]: followed by "systemctl start shairport-sync.service" again. Dec 19 11:39:00 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:00 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:00 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:39:00 volumio4x sudo[7123]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:00 volumio4x sudo[7123]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:00 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:00 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:00 volumio4x sudo[7102]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 11:39:00 volumio4x sudo[7102]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 11:39:00 volumio4x sudo[7102]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x sudo[7130]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:00 volumio4x sudo[7130]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:00 volumio4x systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 19 11:39:00 volumio4x sudo[7130]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x sudo[7123]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x volumio[1460]: info: Executing endpoint tc_getconfig Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 19 11:39:00 volumio4x vtcs[7135]: STARTING TidalConnect services, version: 1.5.2.56 Dec 19 11:39:00 volumio4x vtcs[7135]: STARTED TidalConnect services. Dec 19 11:39:00 volumio4x volumio[1460]: info: Executing endpoint tc_connect Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 19 11:39:00 volumio4x volumio[1460]: info: Connecting to TidalConnect Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:00 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:00 volumio4x volumio[1460]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:00 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:00 volumio4x volumio[1460]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: SPOTIFY VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: VOLUMIO VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: SPOTIFY VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: VOLUMIO VOLUME 100 Dec 19 11:39:00 volumio4x volumio[1460]: SPOTIFY: DELTA VOLUME ENOUGH: false Dec 19 11:39:00 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Dec 19 11:39:00 volumio4x sudo[7161]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 11:39:00 volumio4x sudo[7161]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:00 volumio4x systemd[1]: mpd.service: Deactivated successfully. Dec 19 11:39:00 volumio4x systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 11:39:00 volumio4x systemd[1]: mpd.socket: Deactivated successfully. Dec 19 11:39:00 volumio4x systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 11:39:00 volumio4x systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 11:39:00 volumio4x systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 11:39:00 volumio4x systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 11:39:00 volumio4x sudo[7164]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 11:39:00 volumio4x sudo[7164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 11:39:00 volumio4x sudo[7164]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:00 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:01 volumio4x volumio[1460]: info: VolumeController::SetAlsaVolume72 Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:01 volumio4x mpd[7166]: 2025-12-19T11:39:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 11:39:01 volumio4x systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 11:39:01 volumio4x sudo[6934]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x sudo[7060]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x sudo[7161]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x sudo[6841]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x sudo[6998]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:01 volumio4x volumio[1460]: SPOTIFY: SPOTIFY VOLUME 100 Dec 19 11:39:01 volumio4x volumio[1460]: SPOTIFY: VOLUMIO VOLUME 72 Dec 19 11:39:01 volumio4x volumio[1460]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 19 11:39:01 volumio4x volumio[1460]: info: Setting Spotify Volume from Volumio: 72 Dec 19 11:39:01 volumio4x volumio[1460]: error: MPD error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: MPD error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: MPD error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: MPD error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: The expression evaluated to a falsy value: Dec 19 11:39:01 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:01 volumio4x volumio[1460]: error: updateQueue error: null Dec 19 11:39:01 volumio4x qobuz-connect[6990]: 20251219 11:39:01.836 [6990.6990] INFO SampleApp: shat down connection on UNIX socket Dec 19 11:39:01 volumio4x volumio[1460]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 19 11:39:01 volumio4x volumio[1460]: info: QobuzConnect: setDeactiveState invoked Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:01 volumio4x systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=11/SEGV Dec 19 11:39:01 volumio4x systemd[1]: qobuz-connect.service: Failed with result 'signal'. Dec 19 11:39:01 volumio4x systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 11:39:01 volumio4x systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 19 11:39:01 volumio4x sudo[7042]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x sudo[7107]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:01 volumio4x volumio[1460]: info: Executing endpoint qc_getconfig Dec 19 11:39:01 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.893 [7182.7182] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.895 [7182.7182] INFO VolumeManager: [0x13cccb8]: Setting new playback volume: 75 Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.895 [7182.7182] INFO VolumeManager: [0x13cccb8]: Setting new mute state: 0 Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.895 [7182.7182] INFO QobuzConnect: [0x13cd688]: Client initialized! Dec 19 11:39:01 volumio4x volumio[1460]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 19 11:39:01 volumio4x volumio[1460]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.895 [7182.7182] INFO SampleApp: Starting Avahi advertising, name: Volumio4x, service name: _qobuz-connect._tcp Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.906 [7182.7182] INFO LocalConfigManager: [0x13cc598]: Starting Local Configuration server Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.906 [7182.7182] INFO SampleApp: Starting Local configuration server Dec 19 11:39:01 volumio4x qobuz-connect[7182]: 20251219 11:39:01.907 [7182.7182] INFO SampleApp: Connected to UNIX socket client 0x13b7818 Dec 19 11:39:01 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x qobuz-connect[7182]: 20251219 11:39:02.043 [7182.7182] INFO SampleApp: Playback volume changed: 75 Dec 19 11:39:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x sudo[7201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:02 volumio4x sudo[7201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x sudo[7201]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x sudo[7212]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:02 volumio4x sudo[7212]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x sudo[7220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:02 volumio4x sudo[7220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x sudo[7212]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x sudo[7220]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x sudo[7225]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:02 volumio4x volumio[1460]: error: updateQueue error: null Dec 19 11:39:02 volumio4x sudo[7225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x sudo[7227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:02 volumio4x sudo[7227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 11:39:02 volumio4x systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 11:39:02 volumio4x systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 11:39:02 volumio4x systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 11:39:02 volumio4x sudo[7227]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x sudo[7225]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x mpd_monitor.sh[7230]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 11:39:02 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:02 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x sudo[7243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:02 volumio4x sudo[7243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x sudo[7243]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:02 volumio4x sudo[7255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:02 volumio4x sudo[7255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:02 volumio4x sudo[7255]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:02 volumio4x volumio[1460]: SPOTIFY: SETTING SPOTIFY VOLUME 72 Dec 19 11:39:02 volumio4x volumio[1460]: info: Sending Spotify command with payload to local API: /player/volume Dec 19 11:39:03 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:03 volumio4x volumio[1460]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:03 volumio4x volumio[1460]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 19 11:39:03 volumio4x volumio[1460]: info: TidalConnect service stoped! Dec 19 11:39:03 volumio4x sudo[7267]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 19 11:39:03 volumio4x sudo[7267]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:03 volumio4x sudo[7267]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:03 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:03 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:04 volumio4x sudo[7271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:04 volumio4x sudo[7271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:04 volumio4x sudo[7273]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:04 volumio4x sudo[7273]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:04 volumio4x sudo[7279]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:04 volumio4x sudo[7279]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:04 volumio4x sudo[7277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:04 volumio4x sudo[7277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:04 volumio4x sudo[7281]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 11:39:04 volumio4x sudo[7281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 11:39:04 volumio4x systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 11:39:04 volumio4x systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 11:39:04 volumio4x systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 11:39:04 volumio4x volumio[1460]: error: MPD error: The expression evaluated to a falsy value: Dec 19 11:39:04 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:04 volumio4x volumio[1460]: error: The expression evaluated to a falsy value: Dec 19 11:39:04 volumio4x volumio[1460]: assert.ok(self.idling) Dec 19 11:39:04 volumio4x volumio[1460]: error: updateQueue error: null Dec 19 11:39:04 volumio4x systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 11:39:04 volumio4x sudo[7273]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:04 volumio4x sudo[7279]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:04 volumio4x mpd_monitor.sh[7285]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 11:39:04 volumio4x sudo[7271]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:04 volumio4x sudo[7281]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:04 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:04 volumio4x sudo[7277]: pam_unix(sudo:session): session closed for user root Dec 19 11:39:04 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:04 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:04 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:04 volumio4x volumio[1460]: info: Successfully started MPD Monitor Dec 19 11:39:05 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:05 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:05 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:05 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:05 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:05 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:06 volumio4x volumio[1460]: info: TidalConnect service started! Dec 19 11:39:07 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:07 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:07 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:07 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:07 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:07 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPrevious Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreStateMachine::previous Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreStateMachine::updateTrackBlock Dec 19 11:39:08 volumio4x volumio[1460]: info: CorePlayQueue::getTrackBlock Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:08 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:08 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPlay Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::play index 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::stop Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::play index undefined Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::startPlaybackTimer Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: [1766119155390] ControllerTidal::clearAddPlayTrack Dec 19 11:39:15 volumio4x volumio[1460]: info: Getting stream with soundQuality HI_RES Dec 19 11:39:15 volumio4x volumio[1460]: info: getStreamUrl took 398 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand stop Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand stop took 0 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand clear Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand clear took 0 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==" Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand add "http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==" took 3 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 3ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand play Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces system playlist update Dec 19 11:39:15 volumio4x volumio[1460]: info: Ignoring MPD Status Update Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 4ms Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand play took 2 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 2ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 1ms Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 142 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 143 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 142 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 142 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 142 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 144 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 144 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 144 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 4 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"482 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus stop Dec 19 11:39:15 volumio4x volumio[1460]: info: ------------------------------ 150ms Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: Dec 19 11:39:15 volumio4x volumio[1460]: ---------------------------- MPD announces state update: player Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 3 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 3 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 3 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: info: sendMpdCommand status took 1 milliseconds Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:39:15 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"493 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:15 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"465 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:15 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:15 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:15 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:15 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:15 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:15 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:15 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 265ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 264ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 265ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 265ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 265ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 264ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 264ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 125ms Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 115 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 113 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 114 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 114 milliseconds Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:39:16 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":710,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"552 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:39:16 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:39:16 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:16 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 200ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 195ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 194ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 195ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 194ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 194ms Dec 19 11:39:16 volumio4x volumio[1460]: info: ------------------------------ 195ms Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:16 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Dec 19 11:39:19 volumio4x volumio[1460]: info: VolumeController::SetAlsaVolume82 Dec 19 11:39:19 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:39:19 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:39:19 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:39:19 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:19 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:39:19 volumio4x volumio[1460]: SPOTIFY: SPOTIFY VOLUME 72 Dec 19 11:39:19 volumio4x volumio[1460]: SPOTIFY: VOLUMIO VOLUME 82 Dec 19 11:39:19 volumio4x volumio[1460]: SPOTIFY: DELTA VOLUME ENOUGH: true Dec 19 11:39:19 volumio4x volumio[1460]: info: Setting Spotify Volume from Volumio: 82 Dec 19 11:39:20 volumio4x volumio[1460]: SPOTIFY: SETTING SPOTIFY VOLUME 82 Dec 19 11:39:20 volumio4x volumio[1460]: info: Sending Spotify command with payload to local API: /player/volume Dec 19 11:39:22 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:24 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:24 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:24 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:24 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:24 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:24 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:26 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 19 11:39:26 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 19 11:39:29 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:38 volumio4x volumio[1460]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Dec 19 11:39:38 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Dec 19 11:39:39 volumio4x volumio[1460]: info: peppy_screensaver: Start PeppyMeter Dec 19 11:39:40 volumio4x volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 Dec 19 11:39:40 volumio4x volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 10 Dec 19 11:39:40 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:41 volumio4x volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 Dec 19 11:39:41 volumio4x volumio[1460]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 11 Dec 19 11:39:41 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:54 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:39:56 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:56 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:56 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:56 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:56 volumio4x volumio[1460]: info: Executing endpoint metavolumio Dec 19 11:39:56 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: Retrieving Cloud Streaming UI Dec 19 11:39:57 volumio4x volumio[1460]: info: Getting Tidal Cloud Configuration Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: Getting Qobuz Cloud Configuration Dec 19 11:39:57 volumio4x volumio[1460]: info: Asking plugin for UI Config Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: Getting Spotify Cloud Configuration Dec 19 11:39:57 volumio4x volumio[1460]: info: Asking plugin for UI Config Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: Saving Spotify Acccount Dec 19 11:39:57 volumio4x volumio[1460]: info: Got it Dec 19 11:39:57 volumio4x volumio[1460]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 19 11:39:57 volumio4x volumio[1460]: info: Got Tidal Cloud Configuration Dec 19 11:39:57 volumio4x volumio[1460]: info: Got it Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetBrowseSources Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 19 11:39:57 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand update Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand update took 3 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: Dec 19 11:40:01 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 6 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 5 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 5 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 5 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 5 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 5 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 4 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 4 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 3 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 3 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 2 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand status took 1 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatetrue Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 86 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 76 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 50 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 36 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 27 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 19 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 10 milliseconds Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:01 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46217,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:01 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:01 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 159ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 159ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 159ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 158ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:01 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:01 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: MPD Database updated - AlbumList cache refreshed Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: database Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: Dec 19 11:40:02 volumio4x volumio[1460]: ---------------------------- MPD announces state update: update Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::getState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand status Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 12 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 11 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 11 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 11 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 10 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 9 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 8 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 8 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 8 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 81 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 75 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand status took 75 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 60 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: info: Command Router : Notfying DB Updatefalse Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::Close All Modals sent Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseState Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 106ms Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 88 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 78 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 78 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 70 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 70 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 63 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 56 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 47 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 39 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: info: sendMpdCommand playlistinfo took 20 milliseconds Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: verbose: ControllerMpd::parseTrackInfo Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ControllerMpd::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::servicePushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CorePlayQueue::getTrack 17 Dec 19 11:40:02 volumio4x volumio[1460]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46767,"duration":275,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"576 Kbps","isStreaming":false,"title":"0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","artist":null,"album":null,"uri":"http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiRkNDkwNDg1NWEwYjlhYjBiNzJmYTE1Zjc4NWE5OGE4OS5tcDQ/0.flac?token=1766122713~MTg5Y2UyZTM2NDVmODliZGFkOTM0OWVlNjNlMmU2YTMyMTVjY2U0Zg==","trackType":"tidal"} Dec 19 11:40:02 volumio4x volumio[1460]: verbose: CURRENT POSITION 17 Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState stateService play Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::syncState currentStatus play Dec 19 11:40:02 volumio4x volumio[1460]: info: Received an update from plugin. extracting info from payload Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreStateMachine::pushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioPushState Dec 19 11:40:02 volumio4x volumio[1460]: info: CoreCommandRouter::volumioGetState Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 158ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 158ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 156ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 156ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 156ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 157ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 156ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 156ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 155ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 155ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 154ms Dec 19 11:40:02 volumio4x volumio[1460]: info: ------------------------------ 154ms Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:02 volumio4x volumio[1460]: SPOTIFY: RECEIVED VOLUMIO VOLUME 82 Dec 19 11:40:03 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Dec 19 11:40:03 volumio4x volumio[1460]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Dec 19 11:40:03 volumio4x volumio[1460]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 11:40:03 volumio4x volumio[1460]: Error: getaddrinfo -3007 Dec 19 11:40:03 volumio4x volumio[1460]: at errnoException (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:199:11) Dec 19 11:40:03 volumio4x volumio[1460]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:112:10) Dec 19 11:40:03 volumio4x volumio[1460]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) { Dec 19 11:40:03 volumio4x volumio[1460]: code: -3007, Dec 19 11:40:03 volumio4x volumio[1460]: errno: -3007, Dec 19 11:40:03 volumio4x volumio[1460]: syscall: 'getaddrinfo' Dec 19 11:40:03 volumio4x volumio[1460]: } Dec 19 11:40:03 volumio4x volumio[1460]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 11:40:04 volumio4x sudo[7903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 11:39' Dec 19 11:40:04 volumio4x sudo[7903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="6afb90945e8ed48e8055051e2b7fdfaa52c3637b" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="2f25e1a98e53e2dbc4f3878604e250b0379f3ea4" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed Dec 17 16:02:38 UTC 2025" VOLUMIO_VERSION="4.081" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="441a598d858427165f18cef3d6ec4be3"