-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2025-11-03 22:11:08 CET. -- Nov 03 22:10:00 volumio systemd[1]: Stopping Music Player Daemon... Nov 03 22:10:00 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:10:00 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:10:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Nov 03 22:10:00 volumio volumio[31889]: info: MRS: Pushing multiroomSync output for this device Nov 03 22:10:00 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:00 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:10:00 volumio volumio[31889]: info: Adding audio output: Nov 03 22:10:00 volumio sudo[32244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Nov 03 22:10:00 volumio sudo[32244]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio volumio[31889]: info: Adding audio output: Nov 03 22:10:00 volumio sudo[32244]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Nov 03 22:10:00 volumio volumio[31889]: info: Adding METAVOLUMIO REST API Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 03 22:10:00 volumio volumio[31889]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 03 22:10:00 volumio volumio[31889]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 03 22:10:00 volumio volumio[31889]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Nov 03 22:10:00 volumio volumio[31889]: info: Preparing CD Folders Nov 03 22:10:00 volumio volumio[31889]: info: Adding CD REST API Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Nov 03 22:10:00 volumio volumio[31889]: info: Starting UDEV Watcher for CD Nov 03 22:10:00 volumio volumio[31889]: info: Detecting CD presence with UDEV Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Nov 03 22:10:00 volumio volumio[31889]: info: Adding inputs REST Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 03 22:10:00 volumio volumio[31889]: info: Scanning Audio Inputs Nov 03 22:10:00 volumio sudo[32240]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:10:00 volumio sudo[32240]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio sudo[32240]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio volumio[31889]: info: Checking against Known Cards name Nov 03 22:10:00 volumio volumio[31889]: info: Adding Server instance for streaming Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Nov 03 22:10:00 volumio volumio[31889]: error: Hi Res Audio Failed Login: Missing Login Data Nov 03 22:10:00 volumio volumio[31889]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Nov 03 22:10:00 volumio volumio[31889]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Nov 03 22:10:00 volumio volumio[31889]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Nov 03 22:10:00 volumio sudo[32254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:10:00 volumio sudo[32254]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio volumio[31889]: info: QobuzConnect: Starting Qobuz Connect socket and service Nov 03 22:10:00 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 03 22:10:00 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 03 22:10:00 volumio systemd[1]: vtcs.service: Succeeded. Nov 03 22:10:00 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 03 22:10:00 volumio sudo[32254]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio sudo[32260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:10:00 volumio sudo[32260]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio volumio[31889]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Nov 03 22:10:00 volumio volumio[31889]: info: Stopping AccessToken refresher cron Nov 03 22:10:00 volumio sudo[32260]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio sudo[32263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:10:00 volumio sudo[32263]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio volumio[31889]: info: AccessToken refresher cron started Nov 03 22:10:00 volumio volumio[31889]: info: Stopping AccessToken refresher cron for QOBUZ Nov 03 22:10:00 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:10:00 volumio qobuz-connect[31698]: 20251103 22:10:00.507 [31698.31698] INFO SampleApp: Stopping Local configuration server Nov 03 22:10:00 volumio volumio[31889]: info: AccessToken refresher cron started for QOBUZ Nov 03 22:10:00 volumio volumio[31889]: info: Adding TIDAL REST API Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Nov 03 22:10:00 volumio volumio[31889]: info: Adding QOBUZ REST API Endpoints Nov 03 22:10:00 volumio volumio[31889]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 03 22:10:00 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 03 22:10:00 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:10:00 volumio volumio[31889]: info: Successfully Added MyVolumio device Nov 03 22:10:00 volumio volumio[31889]: info: Bluetooth name changed to Volumio Nov 03 22:10:00 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:10:00 volumio sudo[32263]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 22:10:00 volumio volumio[31889]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Nov 03 22:10:00 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.731 [32265.32265] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:10:00 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.738 [32265.32265] INFO VolumeManager: [0x13de9d0]: Setting new playback volume: 75 Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.738 [32265.32265] INFO VolumeManager: [0x13de9d0]: Setting new mute state: 0 Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.738 [32265.32265] INFO QobuzConnect: [0x13df3a0]: Client initialized! Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.738 [32265.32265] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.753 [32265.32265] INFO LocalConfigManager: [0x13de2b0]: Starting Local Configuration server Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.753 [32265.32265] INFO SampleApp: Starting Local configuration server Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.754 [32265.32265] INFO SampleApp: Connected to UNIX socket client 0x13d3f60 Nov 03 22:10:00 volumio volumio[31889]: info: Updating MyVolumio device info Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:00 volumio sudo[32273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:10:00 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:00 volumio sudo[32273]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio volumio[31889]: info: Preparing to generate the ALSA configuration file Nov 03 22:10:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:10:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:10:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:00 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:10:00 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:10:00 volumio volumio[31889]: info: Asound.conf file written Nov 03 22:10:00 volumio sudo[32277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 03 22:10:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:00 volumio sudo[32277]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:00 volumio qobuz-connect[32265]: 20251103 22:10:00.888 [32265.32265] INFO SampleApp: Playback volume changed: 75 Nov 03 22:10:00 volumio sudo[32277]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio sudo[32273]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:00 volumio volumio[31889]: info: Output device has changed, restarting MPD Nov 03 22:10:01 volumio sudo[32284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 22:10:01 volumio sudo[32284]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio sudo[32284]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio volumio[31889]: info: Output device has changed, restarting Shairport Sync Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:01 volumio sudo[32287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 22:10:01 volumio sudo[32287]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 03 22:10:01 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:10:01 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:10:01 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:10:01 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio volumio[31889]: info: Preparing to generate the ALSA configuration file Nov 03 22:10:01 volumio volumio[31889]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 03 22:10:01 volumio volumio[31889]: info: Reading ALSA contributions from plugins. Nov 03 22:10:01 volumio sudo[32299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:10:01 volumio sudo[32299]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio volumio[31889]: info: Successfully Updated MyVolumio device Nov 03 22:10:01 volumio volumio[31889]: info: Successfully Added MyVolumio device Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio sudo[32292]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:10:01 volumio sudo[32292]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio sudo[32299]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio sudo[32292]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:10:01 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:10:01 volumio sudo[32302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:10:01 volumio sudo[32302]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio qobuz-connect[32265]: 20251103 22:10:01.346 [32265.32265] INFO SampleApp: Stopping Local configuration server Nov 03 22:10:01 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:10:01 volumio qobuz-connect[32265]: 20251103 22:10:01.357 [32265.32265] INFO SampleApp: shat down connection on UNIX socket Nov 03 22:10:01 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:01 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:10:01 volumio sudo[32302]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:10:01 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:10:01 volumio sudo[32311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:10:01 volumio sudo[32311]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.438 [32309.32309] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.446 [32309.32309] INFO VolumeManager: [0x10789d0]: Setting new playback volume: 75 Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.446 [32309.32309] INFO VolumeManager: [0x10789d0]: Setting new mute state: 0 Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.446 [32309.32309] INFO QobuzConnect: [0x10793a0]: Client initialized! Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.446 [32309.32309] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:10:01 volumio volumio[31889]: info: Asound.conf file written Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.470 [32309.32309] INFO LocalConfigManager: [0x10782b0]: Starting Local Configuration server Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.470 [32309.32309] INFO SampleApp: Starting Local configuration server Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.471 [32309.32309] INFO SampleApp: Connected to UNIX socket client 0x106df60 Nov 03 22:10:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:10:01 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:10:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:01 volumio sudo[32316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 03 22:10:01 volumio sudo[32316]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio sudo[32316]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:01 volumio sudo[32311]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio volumio[31889]: info: Output device has changed, restarting MPD Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.588 [32309.32309] INFO SampleApp: Playback volume changed: 75 Nov 03 22:10:01 volumio sudo[32323]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 22:10:01 volumio volumio[31889]: info: Output device has changed, restarting Shairport Sync Nov 03 22:10:01 volumio sudo[32323]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:01 volumio sudo[32323]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio sudo[32326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 22:10:01 volumio sudo[32326]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 03 22:10:01 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:10:01 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:10:01 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:10:01 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:10:01 volumio sudo[32339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:10:01 volumio sudo[32339]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio volumio[31889]: info: Successfully Updated MyVolumio device Nov 03 22:10:01 volumio sudo[32339]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:10:01 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio sudo[32341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio sudo[32341]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.925 [32309.32309] INFO SampleApp: Stopping Local configuration server Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:01 volumio qobuz-connect[32309]: 20251103 22:10:01.936 [32309.32309] INFO SampleApp: shat down connection on UNIX socket Nov 03 22:10:01 volumio sudo[32332]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:10:01 volumio sudo[32332]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:01 volumio sudo[32332]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:01 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 03 22:10:01 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 22:10:01 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:10:01 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:01 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:01 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:10:02 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:10:02 volumio sudo[32341]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:02 volumio sudo[32355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:10:02 volumio sudo[32355]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:02 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:10:02 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.092 [32353.32353] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:10:02 volumio sudo[32359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Nov 03 22:10:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:10:02 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:10:02 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.104 [32353.32353] INFO VolumeManager: [0x6a49d0]: Setting new playback volume: 75 Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.104 [32353.32353] INFO VolumeManager: [0x6a49d0]: Setting new mute state: 0 Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.104 [32353.32353] INFO QobuzConnect: [0x6a53a0]: Client initialized! Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.104 [32353.32353] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:10:02 volumio sudo[32359]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:02 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:10:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.130 [32353.32353] INFO LocalConfigManager: [0x6a42b0]: Starting Local Configuration server Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.130 [32353.32353] INFO SampleApp: Starting Local configuration server Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.130 [32353.32353] INFO SampleApp: Connected to UNIX socket client 0x699f60 Nov 03 22:10:02 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:10:02 volumio sudo[32355]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:02 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:10:02 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0bhI3m82sAax3LVDpuqaWu Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5pbu82rBtd4x1T5zv4AQNG Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:47DTgukfewxwLF2dbkznCC Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0vBqDdVYnqGAD41TsizN73 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2N1FpaKgmj2a750NMkwxJU Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2WfUcK7vW9wx6c1MY8ilFX Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:1Psdy31TgPmhfjG4NymhBC Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2ZK59ad7I739Z9WNp7ElkB Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0wAIf7sGRCY7xaLKMGg9ng Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0rZ9BYUBlk7XovvPWgsyY4 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2izgH2Pm98ndqncckvUKSD Nov 03 22:10:02 volumio autossh[4630]: received signal to exit (15) Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:6qF0SR9i7pojaAgHdUO2Iz Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3kxcjujR0wQfRSTqxciDYj Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3IECYTevGj1coNIoKnEsB3 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3y18T27UKImbN8EyNPgBnE Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5LOTtadWBsGDLMQMC0tl2z Nov 03 22:10:02 volumio qobuz-connect[32353]: 20251103 22:10:02.242 [32353.32353] INFO SampleApp: Playback volume changed: 75 Nov 03 22:10:02 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Nov 03 22:10:02 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:7GwYotbSe67ksSTtIOaqCj Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5DJgjnQaG1DLM8XmvVEeS0 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:7BOUHKBXLNckvD59Gw2IqA Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:5zy6PlQPiu8UKl8oAKuKiL Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2fT8tRML4PUZa1cZZ5bPZz Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2pU7oYLFcMtgC87h7QyyvB Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2VbFPkx6Pk7CjCYERO4PKU Nov 03 22:10:02 volumio systemd[1]: sshtunnel.service: Succeeded. Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:45Q8FBzKZSIG5KsIe7VabG Nov 03 22:10:02 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:7Gh6J73NeF1bgtyijbgO2k Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2Ektwgee5sBRDqVToA9J0u Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0Xz7tgOxs2dOR4OeD9iumx Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:6WOGxTKxnLB1QZGXiC0EvG Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:25WUVx5L2TyqPAo2RFIIVh Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0c2Y7YWGOEDQTR1s7seczw Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3uEwWr5IatFzC0LYqhzD08 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:7EVXquOqzdcb7cBLnRrHR0 Nov 03 22:10:02 volumio systemd[1]: Started MyVolumio SSH Tunnel. Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:38jN7CrEA0ike1GOUSUzBQ Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2JxEIs9DVrLmstH6RdZzRd Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2tl8AJW59Re1lYZySsVwD0 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:3XIQfhK8lFH9O9do9YXkmF Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:2cTEraaDZNwEg4fyXJicf3 Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:0l4fHFJ0qzr1o61jnViaGc Nov 03 22:10:02 volumio volumio[31889]: info: Preloading song: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:02 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:02 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:0bhI3m82sAax3LVDpuqaWu in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0bhI3m82sAax3LVDpuqaWu Nov 03 22:10:02 volumio sudo[32359]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:02 volumio volumio[31889]: info: Remote SSH Started Nov 03 22:10:02 volumio autossh[32366]: port set to 0, monitoring disabled Nov 03 22:10:02 volumio autossh[32366]: starting ssh (count 1) Nov 03 22:10:02 volumio autossh[32366]: ssh child pid is 32369 Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:3qBodHk8X2Zn2s1u5WXw7W in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:3qBodHk8X2Zn2s1u5WXw7W in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:3qBodHk8X2Zn2s1u5WXw7W in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5pbu82rBtd4x1T5zv4AQNG in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5pbu82rBtd4x1T5zv4AQNG Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:1eeZ3krQ0uVeMVCJhjKteP in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:1eeZ3krQ0uVeMVCJhjKteP in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:02 volumio volumio[31889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 03 22:10:02 volumio volumiossh-tunnel[32364]: Warning: Permanently added '[eu2.myvolumio.org]:2222,[185.14.187.123]:2222' (RSA) to the list of known hosts. Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5WbiNJnDjXQTZtICDiXZOL in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:02 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5WbiNJnDjXQTZtICDiXZOL in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5WbiNJnDjXQTZtICDiXZOL in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5WbiNJnDjXQTZtICDiXZOL in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:5WbiNJnDjXQTZtICDiXZOL in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:47DTgukfewxwLF2dbkznCC in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:47DTgukfewxwLF2dbkznCC Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:0vBqDdVYnqGAD41TsizN73 in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0vBqDdVYnqGAD41TsizN73 Nov 03 22:10:02 volumio volumio[31889]: info: Exploding uri spotify:track:2N1FpaKgmj2a750NMkwxJU in service spop Nov 03 22:10:02 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2N1FpaKgmj2a750NMkwxJU Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2WfUcK7vW9wx6c1MY8ilFX in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2WfUcK7vW9wx6c1MY8ilFX Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:1Psdy31TgPmhfjG4NymhBC in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:1Psdy31TgPmhfjG4NymhBC Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qBodHk8X2Zn2s1u5WXw7W","service":"spop","name":"Calling Elvis - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":625,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio sudo[32371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Nov 03 22:10:03 volumio sudo[32371]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:03 volumio sudo[32371]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2ZK59ad7I739Z9WNp7ElkB in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2ZK59ad7I739Z9WNp7ElkB Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:0wAIf7sGRCY7xaLKMGg9ng in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0wAIf7sGRCY7xaLKMGg9ng Nov 03 22:10:03 volumio volumio[31889]: info: Volumio BT Module successfully started Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:0rZ9BYUBlk7XovvPWgsyY4 in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0rZ9BYUBlk7XovvPWgsyY4 Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2izgH2Pm98ndqncckvUKSD in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2izgH2Pm98ndqncckvUKSD Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qBodHk8X2Zn2s1u5WXw7W","service":"spop","name":"Calling Elvis - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":625,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:6qF0SR9i7pojaAgHdUO2Iz in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:6qF0SR9i7pojaAgHdUO2Iz Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:3kxcjujR0wQfRSTqxciDYj in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3kxcjujR0wQfRSTqxciDYj Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3qBodHk8X2Zn2s1u5WXw7W","service":"spop","name":"Calling Elvis - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":625,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WbiNJnDjXQTZtICDiXZOL","service":"spop","name":"Brothers In Arms - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:47DTgukfewxwLF2dbkznCC","service":"spop","name":"Walking On A Dream","artist":"Viva La Panda","album":"Walking On A Dream","type":"song","duration":199,"albumart":"https://i.scdn.co/image/ab67616d0000b273af60e18a0fdd6c754835c92c","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: info: TidalConnect service stoped! Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:3IECYTevGj1coNIoKnEsB3 in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3IECYTevGj1coNIoKnEsB3 Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:3y18T27UKImbN8EyNPgBnE in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3y18T27UKImbN8EyNPgBnE Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:5LOTtadWBsGDLMQMC0tl2z in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5LOTtadWBsGDLMQMC0tl2z Nov 03 22:10:03 volumio volumio[31889]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 03 22:10:03 volumio volumio[31889]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WbiNJnDjXQTZtICDiXZOL","service":"spop","name":"Brothers In Arms - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1eeZ3krQ0uVeMVCJhjKteP","service":"spop","name":"Your Latest Trick - Live At Les Arenes, Nimes / France / 1993","artist":"Dire Straits","album":"On The Night","type":"song","duration":335,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:7GwYotbSe67ksSTtIOaqCj in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:7GwYotbSe67ksSTtIOaqCj Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0bhI3m82sAax3LVDpuqaWu","service":"spop","name":"One World - Remastered 1996","artist":"Dire Straits","album":"Brothers In Arms (Remastered 1996)","type":"song","duration":217,"albumart":"https://i.scdn.co/image/ab67616d0000b27358c4e9c84ece7d31f51636f3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:5DJgjnQaG1DLM8XmvVEeS0 in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5DJgjnQaG1DLM8XmvVEeS0 Nov 03 22:10:03 volumio sudo[32382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 03 22:10:03 volumio sudo[32382]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:10:03 volumio mpd[32349]: Nov 03 22:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:7BOUHKBXLNckvD59Gw2IqA in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:7BOUHKBXLNckvD59Gw2IqA Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WbiNJnDjXQTZtICDiXZOL","service":"spop","name":"Brothers In Arms - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WbiNJnDjXQTZtICDiXZOL","service":"spop","name":"Brothers In Arms - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio systemd[1]: Started Volumio Tidal Connect Service. Nov 03 22:10:03 volumio systemd[1]: Started Music Player Daemon. Nov 03 22:10:03 volumio sudo[32326]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:5zy6PlQPiu8UKl8oAKuKiL in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:5zy6PlQPiu8UKl8oAKuKiL Nov 03 22:10:03 volumio sudo[32287]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:03 volumio sudo[32234]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:03 volumio sudo[32382]: pam_unix(sudo:session): session closed for user root Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2fT8tRML4PUZa1cZZ5bPZz in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2fT8tRML4PUZa1cZZ5bPZz Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2pU7oYLFcMtgC87h7QyyvB in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2pU7oYLFcMtgC87h7QyyvB Nov 03 22:10:03 volumio volumio[31889]: error: MPD error: The expression evaluated to a falsy value: Nov 03 22:10:03 volumio volumio[31889]: assert.ok(self.idling) Nov 03 22:10:03 volumio volumio[31889]: error: The expression evaluated to a falsy value: Nov 03 22:10:03 volumio volumio[31889]: assert.ok(self.idling) Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:2VbFPkx6Pk7CjCYERO4PKU in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2VbFPkx6Pk7CjCYERO4PKU Nov 03 22:10:03 volumio volumio[31889]: error: MPD error: The expression evaluated to a falsy value: Nov 03 22:10:03 volumio volumio[31889]: assert.ok(self.idling) Nov 03 22:10:03 volumio volumio[31889]: error: The expression evaluated to a falsy value: Nov 03 22:10:03 volumio volumio[31889]: assert.ok(self.idling) Nov 03 22:10:03 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 22:10:03 volumio volumio[31889]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5WbiNJnDjXQTZtICDiXZOL","service":"spop","name":"Brothers In Arms - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":535,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0rZ9BYUBlk7XovvPWgsyY4","service":"spop","name":"Runnin'","artist":"Ben Reggie","album":"Runnin'","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273dff827b8fd32a9fb82365c28","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: error: updateQueue error: null Nov 03 22:10:03 volumio volumio[31889]: info: Exploding uri spotify:track:45Q8FBzKZSIG5KsIe7VabG in service spop Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:45Q8FBzKZSIG5KsIe7VabG Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5pbu82rBtd4x1T5zv4AQNG","service":"spop","name":"On Every Street - Live Version","artist":"Dire Straits","album":"On The Night","type":"song","duration":421,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1eeZ3krQ0uVeMVCJhjKteP","service":"spop","name":"Your Latest Trick - Live At Les Arenes, Nimes / France / 1993","artist":"Dire Straits","album":"On The Night","type":"song","duration":335,"albumart":"https://i.scdn.co/image/ab67616d0000b2739128ebb3f5905773207ecbe6","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7GwYotbSe67ksSTtIOaqCj","service":"spop","name":"Sunset Lovers","artist":"Thierry Von Der Warth","album":"Restaurant Lounge 2023 Vol. 1 Background Music","type":"song","duration":176,"albumart":"https://i.scdn.co/image/ab67616d0000b273031755d9c4754cd16daddf07","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:1Psdy31TgPmhfjG4NymhBC","service":"spop","name":"Anti-Hero","artist":"One Flame","album":"Anti-Hero","type":"song","duration":140,"albumart":"https://i.scdn.co/image/ab67616d0000b273ccf593ed56def720d0423af5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:03 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3kxcjujR0wQfRSTqxciDYj","service":"spop","name":"That's So True","artist":"Cupido","album":"That's So True","type":"song","duration":170,"albumart":"https://i.scdn.co/image/ab67616d0000b27345d13a035a4978113b58d3bc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:7Gh6J73NeF1bgtyijbgO2k in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:7Gh6J73NeF1bgtyijbgO2k Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2WfUcK7vW9wx6c1MY8ilFX","service":"spop","name":"Take My Hand","artist":"Mecdoux","album":"Take My Hand","type":"song","duration":151,"albumart":"https://i.scdn.co/image/ab67616d0000b273dcf358e22b81bd7a43ff76cb","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2N1FpaKgmj2a750NMkwxJU","service":"spop","name":"Put It On Ice","artist":"Arthur","album":"Put It On Ice","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b27380f68be076c5650691ba6639","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6qF0SR9i7pojaAgHdUO2Iz","service":"spop","name":"Ocean Drive","artist":"Mecdoux","album":"Ocean Drive","type":"song","duration":178,"albumart":"https://i.scdn.co/image/ab67616d0000b2734e1acd6d5f62413160ad23a3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0vBqDdVYnqGAD41TsizN73","service":"spop","name":"Baille","artist":"ERIICE","album":"Baille","type":"song","duration":153,"albumart":"https://i.scdn.co/image/ab67616d0000b2734e93298441dc564af7c50ff5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3IECYTevGj1coNIoKnEsB3","service":"spop","name":"Fly Me To The Moon","artist":"Modern Culture","album":"Fly Me To The Moon","type":"song","duration":180,"albumart":"https://i.scdn.co/image/ab67616d0000b2738dd574543e2c8c5659986e9e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0wAIf7sGRCY7xaLKMGg9ng","service":"spop","name":"Get Lucky","artist":"BNHM","album":"Get Lucky","type":"song","duration":142,"albumart":"https://i.scdn.co/image/ab67616d0000b2736d2587f4fbc8994d8f861507","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:2Ektwgee5sBRDqVToA9J0u in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2Ektwgee5sBRDqVToA9J0u Nov 03 22:10:04 volumio volumio[31889]: info: Executing endpoint tc_getconfig Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2ZK59ad7I739Z9WNp7ElkB","service":"spop","name":"Mixed Feelings","artist":"Sainte Barbe","album":"Mixed Feelings","type":"song","duration":143,"albumart":"https://i.scdn.co/image/ab67616d0000b2735826994e617f1a9147b4a4a8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio vtcs[32386]: STARTING TidalConnect services, version: 1.5.1-beta1 Nov 03 22:10:04 volumio vtcs[32386]: STARTED TidalConnect services. Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5LOTtadWBsGDLMQMC0tl2z","service":"spop","name":"Life","artist":"ConKi","album":"Life","type":"song","duration":130,"albumart":"https://i.scdn.co/image/ab67616d0000b273f5f3161ef0be6bbb2ba53dae","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2izgH2Pm98ndqncckvUKSD","service":"spop","name":"Can't Get You Out Of My Head","artist":"Mecdoux","album":"Restaurant Lounge 2023 Vol. 1 Background Music","type":"song","duration":128,"albumart":"https://i.scdn.co/image/ab67616d0000b273031755d9c4754cd16daddf07","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Executing endpoint tc_connect Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 03 22:10:04 volumio volumio[31889]: info: Connecting to TidalConnect Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:0Xz7tgOxs2dOR4OeD9iumx in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0Xz7tgOxs2dOR4OeD9iumx Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5DJgjnQaG1DLM8XmvVEeS0","service":"spop","name":"Skin - Blaank Remix","artist":"EMOTIONAL","album":"Skin (Blaank Remix)","type":"song","duration":152,"albumart":"https://i.scdn.co/image/ab67616d0000b2735762e5c839244c1b8daf1298","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:6WOGxTKxnLB1QZGXiC0EvG in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:6WOGxTKxnLB1QZGXiC0EvG Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::servicePushState Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 03 22:10:04 volumio volumio[31889]: verbose: CURRENT POSITION 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::syncState stateService stop Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::syncState currentStatus stop Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: No code Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::servicePushState Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Nov 03 22:10:04 volumio volumio[31889]: verbose: CURRENT POSITION 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::syncState stateService stop Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::syncState currentStatus stop Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: No code Nov 03 22:10:04 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:04 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:04 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:04 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:25WUVx5L2TyqPAo2RFIIVh in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:25WUVx5L2TyqPAo2RFIIVh Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:0c2Y7YWGOEDQTR1s7seczw in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0c2Y7YWGOEDQTR1s7seczw Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:3uEwWr5IatFzC0LYqhzD08 in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3uEwWr5IatFzC0LYqhzD08 Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:7EVXquOqzdcb7cBLnRrHR0 in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:7EVXquOqzdcb7cBLnRrHR0 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3y18T27UKImbN8EyNPgBnE","service":"spop","name":"Waiting","artist":"Sickluv","album":"Waiting","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b2732f30b0a8f533dd28bfeb70cc","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:38jN7CrEA0ike1GOUSUzBQ in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:38jN7CrEA0ike1GOUSUzBQ Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7BOUHKBXLNckvD59Gw2IqA","service":"spop","name":"Azizam","artist":"Mecdoux","album":"Azizam","type":"song","duration":174,"albumart":"https://i.scdn.co/image/ab67616d0000b273057eefc3a364ea016a328f90","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:2JxEIs9DVrLmstH6RdZzRd in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2JxEIs9DVrLmstH6RdZzRd Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","service":"spop","name":"Wicked Game","artist":"LÖVEE","album":"Wicked Game","type":"song","duration":127,"albumart":"https://i.scdn.co/image/ab67616d0000b273cbb5809484ca5855c8a3ba99","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:45Q8FBzKZSIG5KsIe7VabG","service":"spop","name":"Paradise","artist":"Thierry Von Der Warth","album":"Paradise","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b273df09f433679e37bd0561483a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2fT8tRML4PUZa1cZZ5bPZz","service":"spop","name":"Nel Blu Dipinto Di Blu (Volare)","artist":"DOLCE VITA","album":"Nel Blu Dipinto Di Blu (Volare)","type":"song","duration":167,"albumart":"https://i.scdn.co/image/ab67616d0000b273a43c983a27d084d4e0d540f4","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2pU7oYLFcMtgC87h7QyyvB","service":"spop","name":"Messy","artist":"Mecdoux","album":"Messy","type":"song","duration":187,"albumart":"https://i.scdn.co/image/ab67616d0000b273ee2e8efb2b7461fd527c8994","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2Ektwgee5sBRDqVToA9J0u","service":"spop","name":"Ordinary","artist":"Cupido","album":"Ordinary","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b27323a7968a3e49f46e36300562","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:2tl8AJW59Re1lYZySsVwD0 in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2tl8AJW59Re1lYZySsVwD0 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2VbFPkx6Pk7CjCYERO4PKU","service":"spop","name":"Manchild","artist":"Cupido","album":"Manchild","type":"song","duration":190,"albumart":"https://i.scdn.co/image/ab67616d0000b2735b428dd38f611f4f0892aee3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5zy6PlQPiu8UKl8oAKuKiL","service":"spop","name":"Summertime Sadness","artist":"LÖVEE","album":"Summertime Sadness","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b273a7bd3ef698f5a3010e412a4a","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:6WOGxTKxnLB1QZGXiC0EvG","service":"spop","name":"Once In A Lifetime Groove","artist":"Mecdoux","album":"Once In A Lifetime Groove","type":"song","duration":161,"albumart":"https://i.scdn.co/image/ab67616d0000b273c5defe329b127467e4717d77","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0Xz7tgOxs2dOR4OeD9iumx","service":"spop","name":"Birds Of A Feather","artist":"Mecdoux","album":"Birds Of A Feather","type":"song","duration":200,"albumart":"https://i.scdn.co/image/ab67616d0000b273c3f53c1be0f2c497720e2367","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0c2Y7YWGOEDQTR1s7seczw","service":"spop","name":"Show Me Love","artist":"Mecdoux","album":"Show Me Love","type":"song","duration":150,"albumart":"https://i.scdn.co/image/ab67616d0000b273f9f6763b1fefc023a02a9fa2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:3XIQfhK8lFH9O9do9YXkmF in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:3XIQfhK8lFH9O9do9YXkmF Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3uEwWr5IatFzC0LYqhzD08","service":"spop","name":"You Said That You Loved Me","artist":"Addal","album":"You Said That You Loved Me","type":"song","duration":149,"albumart":"https://i.scdn.co/image/ab67616d0000b273cc02aec52c6b796711e49238","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:2cTEraaDZNwEg4fyXJicf3 in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:2cTEraaDZNwEg4fyXJicf3 Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:25WUVx5L2TyqPAo2RFIIVh","service":"spop","name":"Just The Two Of Us","artist":"Chill Gull","album":"Just The Two Of Us","type":"song","duration":131,"albumart":"https://i.scdn.co/image/ab67616d0000b273dda957a75b33fa6762bc47d2","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7EVXquOqzdcb7cBLnRrHR0","service":"spop","name":"Am I Wrong","artist":"Mecdoux","album":"Am I Wrong","type":"song","duration":196,"albumart":"https://i.scdn.co/image/ab67616d0000b273f4cab0cc8340358d5010097f","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:04 volumio volumio[31889]: info: Preloading song: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:04 volumio volumio[31889]: info: Preloading song: spotify:track:0l4fHFJ0qzr1o61jnViaGc Nov 03 22:10:04 volumio volumio[31889]: info: Preloading song: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:38jN7CrEA0ike1GOUSUzBQ","service":"spop","name":"Say It Right","artist":"LÖVEE","album":"Say It Right","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b2739faed02e1217c33a1c877b37","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:65nqVZeTJzSlENQvrT971j in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:0l4fHFJ0qzr1o61jnViaGc in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:0l4fHFJ0qzr1o61jnViaGc Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2JxEIs9DVrLmstH6RdZzRd","service":"spop","name":"Wonderful Life","artist":"John Skyfield","album":"Wonderful Life","type":"song","duration":169,"albumart":"https://i.scdn.co/image/ab67616d0000b27330bf541a7f8c363fa88df586","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2tl8AJW59Re1lYZySsVwD0","service":"spop","name":"Stumblin' In","artist":"Mecdoux","album":"Stumblin' In","type":"song","duration":168,"albumart":"https://i.scdn.co/image/ab67616d0000b2738c8bf2ab12e3cb7d993e1c0e","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: info: Exploding uri spotify:track:65nqVZeTJzSlENQvrT971j in service spop Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: EXPLODING URI:spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2cTEraaDZNwEg4fyXJicf3","service":"spop","name":"Best Day Of My Life","artist":"Mecdoux","album":"Best Day Of My Life","type":"song","duration":132,"albumart":"https://i.scdn.co/image/ab67616d0000b273684a858e8ed32f4f1594b7e3","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:3XIQfhK8lFH9O9do9YXkmF","service":"spop","name":"Catch & Release","artist":"LÖVEE","album":"Catch & Release","type":"song","duration":162,"albumart":"https://i.scdn.co/image/ab67616d0000b273e310896aca5687b38be95886","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:04 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:65nqVZeTJzSlENQvrT971j","service":"spop","name":"Follow The Sun","artist":"Foínix","album":"Follow The Sun","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b27321c39a54cb5fee62eb41b6a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:05 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0l4fHFJ0qzr1o61jnViaGc","service":"spop","name":"That's So True","artist":"Mecdoux","album":"That's So True","type":"song","duration":172,"albumart":"https://i.scdn.co/image/ab67616d0000b273f02e2524390c2729510b3b90","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:05 volumio volumio[31889]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:65nqVZeTJzSlENQvrT971j","service":"spop","name":"Follow The Sun","artist":"Foínix","album":"Follow The Sun","type":"song","duration":158,"albumart":"https://i.scdn.co/image/ab67616d0000b27321c39a54cb5fee62eb41b6a1","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 03 22:10:05 volumio volumio[31889]: info: MRS: Getting audio outputs on start Nov 03 22:10:05 volumio volumio[31889]: info: MRS: Requesting all other devices output Nov 03 22:10:06 volumio volumio[31889]: info: TidalConnect service started! Nov 03 22:10:06 volumio volumio[31889]: [Metrics] CommandRouter: 34s 126.44ms Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::volumiosetStartupVolume Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:06 volumio volumio[31889]: info: VolumeController:: Setting startup Volume 100 Nov 03 22:10:06 volumio volumio[31889]: info: VolumeController::SetAlsaVolume100 Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::Close All Modals sent Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::Close All Modals sent Nov 03 22:10:06 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:06 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:06 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:06 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:06 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:06 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:06 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 03 22:10:07 volumio volumio[31889]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 03 22:10:07 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:07 volumio volumio[31889]: info: CorePlayQueue::getTrack 0 Nov 03 22:10:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 03 22:10:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 03 22:10:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 03 22:10:11 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 22:10:11 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:10:11 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 03 22:10:11 volumio volumio-remote-updater[599]: No test mode Nov 03 22:10:11 volumio volumio-remote-updater[599]: No alpha test mode Nov 03 22:10:11 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 03 22:10:11 volumio volumio[31889]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 03 22:10:11 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::volumioGetBrowseSources Nov 03 22:10:12 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 03 22:10:13 volumio go-librespot[32071]: time="2025-11-03T22:10:13+01:00" level=trace msg="sent dealer ping" Nov 03 22:10:13 volumio go-librespot[32071]: time="2025-11-03T22:10:13+01:00" level=trace msg="received dealer pong" Nov 03 22:10:13 volumio volumio[31889]: info: BOOT COMPLETED Nov 03 22:10:13 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 03 22:10:13 volumio volumio[31889]: info: In handleBrowseUri, curUri=spotify/myrecentlyplayedtracks Nov 03 22:10:14 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:21 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:21 volumio volumio[31889]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::ClearQueue Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::stop Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::clearPlayQueue Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::saveQueue Nov 03 22:10:21 volumio volumio[31889]: info: CoreCommandRouter::volumioPushQueue Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::addQueueItems Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::addQueueItems Nov 03 22:10:21 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0bhI3m82sAax3LVDpuqaWu Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0bhI3m82sAax3LVDpuqaWu Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3qBodHk8X2Zn2s1u5WXw7W Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5pbu82rBtd4x1T5zv4AQNG Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5pbu82rBtd4x1T5zv4AQNG Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:1eeZ3krQ0uVeMVCJhjKteP Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5WbiNJnDjXQTZtICDiXZOL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:47DTgukfewxwLF2dbkznCC Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:47DTgukfewxwLF2dbkznCC Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0vBqDdVYnqGAD41TsizN73 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0vBqDdVYnqGAD41TsizN73 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2N1FpaKgmj2a750NMkwxJU Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2N1FpaKgmj2a750NMkwxJU Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2WfUcK7vW9wx6c1MY8ilFX Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2WfUcK7vW9wx6c1MY8ilFX Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:1Psdy31TgPmhfjG4NymhBC Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:1Psdy31TgPmhfjG4NymhBC Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2ZK59ad7I739Z9WNp7ElkB Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2ZK59ad7I739Z9WNp7ElkB Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0wAIf7sGRCY7xaLKMGg9ng Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0wAIf7sGRCY7xaLKMGg9ng Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0rZ9BYUBlk7XovvPWgsyY4 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0rZ9BYUBlk7XovvPWgsyY4 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2izgH2Pm98ndqncckvUKSD Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2izgH2Pm98ndqncckvUKSD Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:6qF0SR9i7pojaAgHdUO2Iz Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:6qF0SR9i7pojaAgHdUO2Iz Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3kxcjujR0wQfRSTqxciDYj Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3kxcjujR0wQfRSTqxciDYj Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3IECYTevGj1coNIoKnEsB3 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3IECYTevGj1coNIoKnEsB3 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3y18T27UKImbN8EyNPgBnE Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3y18T27UKImbN8EyNPgBnE Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5LOTtadWBsGDLMQMC0tl2z Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5LOTtadWBsGDLMQMC0tl2z Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:7GwYotbSe67ksSTtIOaqCj Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:7GwYotbSe67ksSTtIOaqCj Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5DJgjnQaG1DLM8XmvVEeS0 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5DJgjnQaG1DLM8XmvVEeS0 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:7BOUHKBXLNckvD59Gw2IqA Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:7BOUHKBXLNckvD59Gw2IqA Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:5zy6PlQPiu8UKl8oAKuKiL Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:5zy6PlQPiu8UKl8oAKuKiL Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2fT8tRML4PUZa1cZZ5bPZz Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2fT8tRML4PUZa1cZZ5bPZz Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2pU7oYLFcMtgC87h7QyyvB Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2pU7oYLFcMtgC87h7QyyvB Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2VbFPkx6Pk7CjCYERO4PKU Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2VbFPkx6Pk7CjCYERO4PKU Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:45Q8FBzKZSIG5KsIe7VabG Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:45Q8FBzKZSIG5KsIe7VabG Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:7Gh6J73NeF1bgtyijbgO2k Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:7Gh6J73NeF1bgtyijbgO2k Nov 03 22:10:21 volumio volumio[31889]: info: CoreCommandRouter::volumioPushQueue Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::saveQueue Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::updateTrackBlock Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::getTrackBlock Nov 03 22:10:21 volumio volumio[31889]: info: CoreCommandRouter::volumioPlay Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::play index 34 Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::addQueueItems Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::addQueueItems Nov 03 22:10:21 volumio volumio[31889]: info: Preload queue cleared Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2Ektwgee5sBRDqVToA9J0u Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2Ektwgee5sBRDqVToA9J0u Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0Xz7tgOxs2dOR4OeD9iumx Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0Xz7tgOxs2dOR4OeD9iumx Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:6WOGxTKxnLB1QZGXiC0EvG Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:6WOGxTKxnLB1QZGXiC0EvG Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:25WUVx5L2TyqPAo2RFIIVh Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:25WUVx5L2TyqPAo2RFIIVh Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0c2Y7YWGOEDQTR1s7seczw Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0c2Y7YWGOEDQTR1s7seczw Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3uEwWr5IatFzC0LYqhzD08 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3uEwWr5IatFzC0LYqhzD08 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:7EVXquOqzdcb7cBLnRrHR0 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:7EVXquOqzdcb7cBLnRrHR0 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:38jN7CrEA0ike1GOUSUzBQ Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:38jN7CrEA0ike1GOUSUzBQ Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2JxEIs9DVrLmstH6RdZzRd Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2JxEIs9DVrLmstH6RdZzRd Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2tl8AJW59Re1lYZySsVwD0 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2tl8AJW59Re1lYZySsVwD0 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:3XIQfhK8lFH9O9do9YXkmF Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:3XIQfhK8lFH9O9do9YXkmF Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:2cTEraaDZNwEg4fyXJicf3 Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:2cTEraaDZNwEg4fyXJicf3 Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:0l4fHFJ0qzr1o61jnViaGc Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:0l4fHFJ0qzr1o61jnViaGc Nov 03 22:10:21 volumio volumio[31889]: info: Adding Item to queue: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:21 volumio volumio[31889]: info: Using cached record of: spotify:track:65nqVZeTJzSlENQvrT971j Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::stop Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 22:10:21 volumio volumio[31889]: info: CoreCommandRouter::volumioPushQueue Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::saveQueue Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::play index undefined Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::updateTrackBlock Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::getTrackBlock Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:21 volumio volumio[31889]: info: CoreStateMachine::startPlaybackTimer Nov 03 22:10:21 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:21 volumio volumio[31889]: info: [1762204221237] ControllerSpotify::clearAddPlayTrack Nov 03 22:10:21 volumio volumio[31889]: info: Sending Spotify command with payload to local API: /player/play Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="resolved context of track" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=trace msg="emitting websocket event: will_play" Nov 03 22:10:21 volumio volumio[31889]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","play_origin":"go-librespot"}} Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="selected format OGG_VORBIS_320 (2fb7798ec9ddec9ce5af00b0f974cfd21c353ea2)" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="requested aes key for file 2fb7798ec9ddec9ce5af00b0f974cfd21c353ea2, gid: 7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="fetched first chunk of 11, total size is 5587731 bytes" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="created new output device" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=info msg="loaded track \"Wicked Game\" (paused: false, position: 0ms, duration: 127895ms, prefetched: false)" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="fetched chunk 1/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=trace msg="scheduling prefetch in 98s" Nov 03 22:10:21 volumio go-librespot[32071]: time="2025-11-03T22:10:21+01:00" level=trace msg="emitting websocket event: metadata" Nov 03 22:10:21 volumio volumio[31889]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","name":"Wicked Game","artist_names":["LÖVEE","HeɅven","Jessie Villa"],"album_name":"Wicked Game","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02cbb5809484ca5855c8a3ba99","position":0,"duration":127895,"release_date":"year:2024 month:5 day:31","track_number":1,"disc_number":1}} Nov 03 22:10:22 volumio go-librespot[32071]: time="2025-11-03T22:10:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 03 22:10:22 volumio go-librespot[32071]: time="2025-11-03T22:10:22+01:00" level=trace msg="emitting websocket event: playing" Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","resume":false,"play_origin":"go-librespot"}} Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: {"status":"play","service":"spop","title":"Wicked Game","artist":"LÖVEE, HeɅven, Jessie Villa","album":"Wicked Game","albumart":"https://i.scdn.co/image/ab67616d00001e02cbb5809484ca5855c8a3ba99","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","trackType":"spotify","seek":0,"duration":127,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::servicePushState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wicked Game","artist":"LÖVEE, HeɅven, Jessie Villa","album":"Wicked Game","albumart":"https://i.scdn.co/image/ab67616d00001e02cbb5809484ca5855c8a3ba99","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","trackType":"spotify","seek":0,"duration":127,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 22:10:22 volumio volumio[31889]: verbose: CURRENT POSITION 34 Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::syncState stateService play Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::syncState currentStatus stop Nov 03 22:10:22 volumio go-librespot[32071]: time="2025-11-03T22:10:22+01:00" level=debug msg="fetched chunk 3/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:22 volumio go-librespot[32071]: time="2025-11-03T22:10:22+01:00" level=debug msg="fetched chunk 2/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: PUSH STATE SPOTIFY Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: {"status":"play","service":"spop","title":"Wicked Game","artist":"LÖVEE, HeɅven, Jessie Villa","album":"Wicked Game","albumart":"https://i.scdn.co/image/ab67616d00001e02cbb5809484ca5855c8a3ba99","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","trackType":"spotify","seek":0,"duration":127,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::servicePushState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Wicked Game","artist":"LÖVEE, HeɅven, Jessie Villa","album":"Wicked Game","albumart":"https://i.scdn.co/image/ab67616d00001e02cbb5809484ca5855c8a3ba99","uri":"spotify:track:7Gh6J73NeF1bgtyijbgO2k","trackType":"spotify","seek":0,"duration":127,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Nov 03 22:10:22 volumio volumio[31889]: verbose: CURRENT POSITION 34 Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::syncState stateService play Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::syncState currentStatus play Nov 03 22:10:22 volumio volumio[31889]: info: Received an update from plugin. extracting info from payload Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:22 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:22 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: info: CoreStateMachine::pushState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::volumioPushState Nov 03 22:10:22 volumio volumio[31889]: info: MRS: Pushing multiroomSync output update for this device Nov 03 22:10:22 volumio volumio[31889]: info: MRS: Pushing multiroomSync output Nov 03 22:10:22 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:10:22 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:22 volumio volumio[31889]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Nov 03 22:10:33 volumio go-librespot[32071]: time="2025-11-03T22:10:33+01:00" level=debug msg="fetched chunk 4/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:43 volumio go-librespot[32071]: time="2025-11-03T22:10:43+01:00" level=trace msg="sent dealer ping" Nov 03 22:10:43 volumio go-librespot[32071]: time="2025-11-03T22:10:43+01:00" level=trace msg="received dealer pong" Nov 03 22:10:46 volumio go-librespot[32071]: time="2025-11-03T22:10:46+01:00" level=debug msg="fetched chunk 5/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:10:59 volumio go-librespot[32071]: time="2025-11-03T22:10:59+01:00" level=debug msg="fetched chunk 6/10, size: 524288" uri="spotify:track:7Gh6J73NeF1bgtyijbgO2k" Nov 03 22:11:03 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 03 22:11:03 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 03 22:11:05 volumio volumio[31889]: info: Enabling plugin fusiondsp Nov 03 22:11:05 volumio volumio[31889]: info: Loading plugin "fusiondsp"... Nov 03 22:11:05 volumio volumio[31889]: info: Applying required configuration parameters for plugin fusiondsp Nov 03 22:11:05 volumio volumio[31889]: info: Preparing to generate the ALSA configuration file Nov 03 22:11:05 volumio volumio[31889]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 03 22:11:05 volumio volumio[31889]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 03 22:11:05 volumio volumio[31889]: info: Reading ALSA contributions from plugins. Nov 03 22:11:05 volumio volumio[31889]: info: Asound.conf file written Nov 03 22:11:05 volumio sudo[32561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 03 22:11:05 volumio sudo[32561]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:05 volumio sudo[32561]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:05 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:05 volumio volumio[31889]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Nov 03 22:11:05 volumio volumio[31889]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Nov 03 22:11:05 volumio volumio[31889]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Nov 03 22:11:05 volumio volumio[31889]: info: Output device has changed, restarting MPD Nov 03 22:11:05 volumio sudo[32566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 22:11:05 volumio sudo[32566]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:05 volumio volumio[31889]: info: Output device has changed, restarting Shairport Sync Nov 03 22:11:05 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:05 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:05 volumio sudo[32566]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:05 volumio sudo[32569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 22:11:05 volumio sudo[32569]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:05 volumio systemd[1]: Stopping Music Player Daemon... Nov 03 22:11:05 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:05 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:11:05 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:05 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:11:05 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:11:05 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:11:05 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:05 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:05 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:05 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:05 volumio vtcs[32386]: [2025-11-03 22:11:05.994] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Nov 03 22:11:05 volumio vtcs[32386]: [2025-11-03 22:11:05.995] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Nov 03 22:11:06 volumio vtcs[32386]: [2025-11-03 22:11:05.996] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Nov 03 22:11:06 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:11:06 volumio volumio[31889]: info: PLUGIN START: fusiondsp Nov 03 22:11:06 volumio volumio[31889]: info: Loading i18n strings for locale es Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: FusionDsp - mixtype--------------------- Hardware Nov 03 22:11:06 volumio sudo[32578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:06 volumio sudo[32578]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio volumio[31889]: info: Preparing to generate the ALSA configuration file Nov 03 22:11:06 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Nov 03 22:11:06 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 03 22:11:06 volumio volumio[31889]: info: Done. Nov 03 22:11:06 volumio systemd[1]: vtcs.service: Succeeded. Nov 03 22:11:06 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Nov 03 22:11:06 volumio sudo[32578]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32574]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:11:06 volumio sudo[32574]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio sudo[32582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:06 volumio sudo[32582]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio sudo[32574]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio volumio[31889]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 03 22:11:06 volumio volumio[31889]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 03 22:11:06 volumio volumio[31889]: info: Reading ALSA contributions from plugins. Nov 03 22:11:06 volumio sudo[32590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:11:06 volumio sudo[32590]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio sudo[32590]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio volumio[31889]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 03 22:11:06 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:11:06 volumio sudo[32582]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio sudo[32592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:11:06 volumio sudo[32592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:11:06 volumio qobuz-connect[32353]: 20251103 22:11:06.319 [32353.32353] INFO SampleApp: Stopping Local configuration server Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio qobuz-connect[32353]: 20251103 22:11:06.330 [32353.32353] INFO SampleApp: shat down connection on UNIX socket Nov 03 22:11:06 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 03 22:11:06 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:06 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:06 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:11:06 volumio volumio[31889]: info: Asound.conf file unchanged, so no further update is needed Nov 03 22:11:06 volumio volumio[31889]: info: Output device has changed, restarting MPD Nov 03 22:11:06 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:11:06 volumio sudo[32601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:11:06 volumio sudo[32592]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32601]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:11:06 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:11:06 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:06 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:06 volumio sudo[32605]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 22:11:06 volumio sudo[32605]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio sudo[32601]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32605]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio volumio[31889]: info: Output device has changed, restarting Shairport Sync Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:06 volumio sudo[32611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 22:11:06 volumio sudo[32611]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 03 22:11:06 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:11:06 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:11:06 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:11:06 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:06 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:11:06 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:06 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:06 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:06 volumio volumio[31889]: info: Preparing to generate the ALSA configuration file Nov 03 22:11:06 volumio sudo[32621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:06 volumio sudo[32621]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio sudo[32621]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32617]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:11:06 volumio sudo[32617]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio sudo[32617]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:06 volumio sudo[32626]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio volumio[31889]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 03 22:11:06 volumio volumio[31889]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 03 22:11:06 volumio volumio[31889]: info: Reading ALSA contributions from plugins. Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio sudo[32633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:11:06 volumio sudo[32633]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio sudo[32626]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio sudo[32633]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:06 volumio volumio[31889]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 03 22:11:06 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:11:06 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:11:06 volumio sudo[32636]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:11:06 volumio sudo[32636]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:06 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.889 [32599.32599] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.895 [32599.32599] INFO VolumeManager: [0xb139d0]: Setting new playback volume: 75 Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.895 [32599.32599] INFO VolumeManager: [0xb139d0]: Setting new mute state: 0 Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.895 [32599.32599] INFO QobuzConnect: [0xb143a0]: Client initialized! Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.895 [32599.32599] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:11:06 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.918 [32599.32599] INFO LocalConfigManager: [0xb132b0]: Starting Local Configuration server Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.918 [32599.32599] INFO SampleApp: Starting Local configuration server Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.921 [32599.32599] INFO SampleApp: Stopping Local configuration server Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:06 volumio qobuz-connect[32599]: 20251103 22:11:06.931 [32599.32599] INFO SampleApp: shat down connection on UNIX socket Nov 03 22:11:06 volumio qobuz-connect[32599]: qobuz-connect-daemon: /home/volumio/qobuz-connect-daemon/sample_console_app/volumio/pipe.c:199: pipe_connect_callback: Assertion `status == 0' failed. Nov 03 22:11:06 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=6/ABRT Nov 03 22:11:06 volumio systemd[1]: qobuz-connect.service: Failed with result 'signal'. Nov 03 22:11:06 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:06 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 22:11:06 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:06 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:06 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:06 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:11:06 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:11:07 volumio sudo[32636]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio volumio[31889]: info: Asound.conf file unchanged, so no further update is needed Nov 03 22:11:07 volumio volumio[31889]: info: Output device has changed, restarting MPD Nov 03 22:11:07 volumio sudo[32645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:11:07 volumio sudo[32645]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:11:07 volumio sudo[32649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 03 22:11:07 volumio sudo[32649]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio volumio[31889]: info: Output device has changed, restarting Shairport Sync Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:07 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:07 volumio sudo[32649]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio sudo[32652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 03 22:11:07 volumio sudo[32652]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:07 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:07 volumio volumio[31889]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 03 22:11:07 volumio volumio[31889]: xcb_connection_has_error() returned true Nov 03 22:11:07 volumio volumio[31889]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:07 volumio sudo[32645]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:07 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:07 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 03 22:11:07 volumio systemd[1]: mpd.service: Succeeded. Nov 03 22:11:07 volumio systemd[1]: Stopped Music Player Daemon. Nov 03 22:11:07 volumio systemd[1]: Starting Music Player Daemon... Nov 03 22:11:07 volumio sudo[32663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:07 volumio sudo[32663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio sudo[32667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 03 22:11:07 volumio sudo[32667]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio sudo[32667]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio sudo[32663]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio sudo[32662]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 03 22:11:07 volumio sudo[32662]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio sudo[32674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Nov 03 22:11:07 volumio sudo[32674]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio sudo[32662]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:07 volumio volumio[31889]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 03 22:11:07 volumio volumio[31889]: info: MPD Permissions set Nov 03 22:11:07 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:11:07 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.444 [32643.32643] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.459 [32643.32643] INFO VolumeManager: [0x22fd9d0]: Setting new playback volume: 75 Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.459 [32643.32643] INFO VolumeManager: [0x22fd9d0]: Setting new mute state: 0 Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.459 [32643.32643] INFO QobuzConnect: [0x22fe3a0]: Client initialized! Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.460 [32643.32643] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.482 [32643.32643] INFO LocalConfigManager: [0x22fd2b0]: Starting Local Configuration server Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.483 [32643.32643] INFO SampleApp: Starting Local configuration server Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.483 [32643.32643] INFO SampleApp: Connected to UNIX socket client 0x22f2f60 Nov 03 22:11:07 volumio sudo[32674]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:11:07 volumio volumio[31889]: info: Starting Shairport Sync Nov 03 22:11:07 volumio sudo[32684]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Nov 03 22:11:07 volumio sudo[32684]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.551 [32643.32643] INFO SampleApp: Stopping Local configuration server Nov 03 22:11:07 volumio sudo[32687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 03 22:11:07 volumio sudo[32687]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 03 22:11:07 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Nov 03 22:11:07 volumio qobuz-connect[32643]: 20251103 22:11:07.561 [32643.32643] INFO SampleApp: shat down connection on UNIX socket Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: setDeactiveState invoked Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:07 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:07 volumio systemd[1]: qobuz-connect.service: Succeeded. Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:07 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:07 volumio systemd[1]: Started Volumio Qobuz Connect Service. Nov 03 22:11:07 volumio sudo[32684]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 03 22:11:07 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 03 22:11:07 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 03 22:11:07 volumio volumio[31889]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 03 22:11:07 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 03 22:11:07 volumio sudo[32687]: pam_unix(sudo:session): session closed for user root Nov 03 22:11:07 volumio volumio[31889]: info: Executing endpoint qc_getconfig Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.709 [32689.32689] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.715 [32689.32689] INFO VolumeManager: [0x1cb99d0]: Setting new playback volume: 75 Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.716 [32689.32689] INFO VolumeManager: [0x1cb99d0]: Setting new mute state: 0 Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.716 [32689.32689] INFO QobuzConnect: [0x1cba3a0]: Client initialized! Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.716 [32689.32689] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Nov 03 22:11:07 volumio volumio[31889]: info: QobuzConnect: QOBUZ Connect daemon connected Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.737 [32689.32689] INFO LocalConfigManager: [0x1cb92b0]: Starting Local Configuration server Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.738 [32689.32689] INFO SampleApp: Starting Local configuration server Nov 03 22:11:07 volumio volumio[31889]: info: Shairport-Sync Started Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.740 [32689.32689] INFO SampleApp: Connected to UNIX socket client 0x1caef60 Nov 03 22:11:07 volumio qobuz-connect[32689]: 20251103 22:11:07.859 [32689.32689] INFO SampleApp: Playback volume changed: 75 Nov 03 22:11:07 volumio volumio[31889]: info: CoreCommandRouter::volumioGetState Nov 03 22:11:07 volumio volumio[31889]: info: CorePlayQueue::getTrack 34 Nov 03 22:11:07 volumio volumio[31889]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 22:11:07 volumio volumio[31889]: TypeError: Cannot read property 'off' of undefined Nov 03 22:11:07 volumio volumio[31889]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Nov 03 22:11:07 volumio volumio[31889]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 03 22:11:07 volumio volumio[31889]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 03 22:11:07 volumio volumio[31889]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Nov 03 22:11:07 volumio volumio[31889]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Nov 03 22:11:07 volumio volumio[31889]: at Socket.emit (events.js:315:20) Nov 03 22:11:07 volumio volumio[31889]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 03 22:11:07 volumio volumio[31889]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 03 22:11:07 volumio volumio[31889]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 03 22:11:08 volumio sudo[32704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-03 22:10 Nov 03 22:11:08 volumio sudo[32704]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"