-- Logs begin at Thu 2019-02-14 23:11:59 NZDT, end at Fri 2025-04-18 14:30:08 NZST. -- Apr 18 14:30:01 primo kernel: usb 1-1.4: new high-speed USB device number 5 using dwc_otg Apr 18 14:30:01 primo kernel: usb 1-1.4: New USB device found, idVendor=0d8c, idProduct=0004, bcdDevice= 1.01 Apr 18 14:30:01 primo kernel: usb 1-1.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0 Apr 18 14:30:01 primo kernel: usb 1-1.4: Product: USB2.0 High-Speed True HD Audio Apr 18 14:30:01 primo kernel: usb 1-1.4: Manufacturer: C-MEDIA Inc. Apr 18 14:30:01 primo kernel: input: C-MEDIA Inc. USB2.0 High-Speed True HD Audio as /devices/platform/soc/3f980000.usb/usb1/1-1/1-1.4/1-1.4:1.2/0003:0D8C:0004.0002/input/input1 Apr 18 14:30:01 primo kernel: hid-generic 0003:0D8C:0004.0002: input,hidraw0: USB HID v1.00 Device [C-MEDIA Inc. USB2.0 High-Speed True HD Audio] on usb-3f980000.usb-1.4/input2 Apr 18 14:30:01 primo volumio[780]: info: Apr 18 14:30:01 primo volumio[780]: ---------------------------- USB Audio Device Attached Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Apr 18 14:30:01 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::Close All Modals sent Apr 18 14:30:01 primo volumio[780]: info: Preparing to save Alsa Options, stopping services first Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::volumioGetState Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::volumioPause Apr 18 14:30:01 primo volumio[780]: info: CoreStateMachine::pause Apr 18 14:30:01 primo volumio[780]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"USB2.0 High-Speed True HD Audio","alsacard":"Audio"},"i2s":false} Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 14:30:01 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:01 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 14:30:01 primo systemd-udevd[3429]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Apr 18 14:30:01 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:01 primo volumio[780]: info: Setting default mixerSoftMaster for Softvolume device Apr 18 14:30:01 primo volumio[780]: [SpotifyConnect] Creating VLS config file Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo vtcs[2926]: [2025-04-18 14:30:01.696] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Apr 18 14:30:01 primo vtcs[2926]: [2025-04-18 14:30:01.697] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Apr 18 14:30:01 primo volumio[780]: [SpotifyConnect] Creating VLS config file Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:01 primo sudo[3445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 18 14:30:01 primo sudo[3445]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:01 primo systemd[1]: Stopping Volumio Tidal Connect Service... Apr 18 14:30:01 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Apr 18 14:30:01 primo systemd[1]: vtcs.service: Succeeded. Apr 18 14:30:01 primo systemd[1]: Stopped Volumio Tidal Connect Service. Apr 18 14:30:01 primo sudo[3445]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:01 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 18 14:30:01 primo volumio[780]: info: Updating Volume Controller Parameters: Device: 5 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 18 14:30:01 primo volumio[780]: info: Disabling external Volume Control Apr 18 14:30:01 primo volumio[780]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 18 14:30:01 primo volumio[780]: info: Enable softmixer device for audio device undefined Apr 18 14:30:01 primo volumio[780]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 18 14:30:01 primo sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 18 14:30:01 primo sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:01 primo volumio[780]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 18 14:30:01 primo sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 18 14:30:01 primo systemd[1]: Stopping Volspotconnect2 Daemon... Apr 18 14:30:01 primo sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:01 primo systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 18 14:30:01 primo systemd[1]: volspotconnect.service: Succeeded. Apr 18 14:30:01 primo systemd[1]: Stopped Volspotconnect2 Daemon. Apr 18 14:30:01 primo sudo[3465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 18 14:30:01 primo sudo[3465]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo systemd[1]: Started Volspotconnect2 Daemon. Apr 18 14:30:02 primo volumio[3466]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 18 14:30:02 primo volumio[3466]: Reading Config from "/tmp/volspotify.toml" Apr 18 14:30:02 primo volumio[3466]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 18 14:30:02 primo volumio[780]: info: CoreStateMachine::pushState Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::volumioPushState Apr 18 14:30:02 primo sudo[3459]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: info: MRS: Pushing multiroomSync output update for this device Apr 18 14:30:02 primo volumio[780]: info: MRS: Pushing multiroomSync output Apr 18 14:30:02 primo systemd[1]: Stopping Volspotconnect2 Daemon... Apr 18 14:30:02 primo systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 18 14:30:02 primo systemd[1]: volspotconnect.service: Succeeded. Apr 18 14:30:02 primo systemd[1]: Stopped Volspotconnect2 Daemon. Apr 18 14:30:02 primo sudo[3462]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo systemd[1]: Started Volspotconnect2 Daemon. Apr 18 14:30:02 primo sudo[3465]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[3470]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 18 14:30:02 primo volumio[3470]: Reading Config from "/tmp/volspotify.toml" Apr 18 14:30:02 primo volumio[3470]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 18 14:30:02 primo volumio[780]: info: Software Volume ALSA configuration written Apr 18 14:30:02 primo volumio[780]: info: Preparing to generate the ALSA configuration file Apr 18 14:30:02 primo volumio[3470]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 18 14:30:02 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 18 14:30:02 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 18 14:30:02 primo volumio[780]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Apr 18 14:30:02 primo volumio[780]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 18 14:30:02 primo volumio[780]: info: Reading ALSA contributions from plugins. Apr 18 14:30:02 primo volumio[780]: info: Asound.conf file unchanged, so no further update is needed Apr 18 14:30:02 primo volumio[780]: info: Output device has changed, restarting MPD Apr 18 14:30:02 primo sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 18 14:30:02 primo sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: info: Output device has changed, restarting Shairport Sync Apr 18 14:30:02 primo sudo[3495]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 14:30:02 primo sudo[3498]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 18 14:30:02 primo sudo[3498]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo systemd[1]: Stopping Music Player Daemon... Apr 18 14:30:02 primo volumio[3470]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Apr 18 14:30:02 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:02 primo volumio[780]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 18 14:30:02 primo volumio[780]: xcb_connection_has_error() returned true Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Creating VLS config file Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo systemd[1]: mpd.service: Succeeded. Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo systemd[1]: Stopped Music Player Daemon. Apr 18 14:30:02 primo volumio[780]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo systemd[1]: Starting Music Player Daemon... Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Creating VLS config file Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 18 14:30:02 primo sudo[3508]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 18 14:30:02 primo sudo[3508]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 18 14:30:02 primo sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Restarting Vollibrespot Daemon Apr 18 14:30:02 primo sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Apr 18 14:30:02 primo sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Apr 18 14:30:02 primo systemd[1]: Stopping Volspotconnect2 Daemon... Apr 18 14:30:02 primo systemd[1]: volspotconnect.service: Main process exited, code=killed, status=15/TERM Apr 18 14:30:02 primo systemd[1]: volspotconnect.service: Succeeded. Apr 18 14:30:02 primo systemd[1]: Stopped Volspotconnect2 Daemon. Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo sudo[3519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volspotconnect.service Apr 18 14:30:02 primo sudo[3519]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: info: MPD Permissions set Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo sudo[3503]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 18 14:30:02 primo sudo[3503]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo sudo[3508]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo sudo[3503]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 18 14:30:02 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo volumio[780]: info: Starting Shairport Sync Apr 18 14:30:02 primo sudo[3529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 18 14:30:02 primo sudo[3529]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:02 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 18 14:30:02 primo systemd[1]: Started Volspotconnect2 Daemon. Apr 18 14:30:02 primo sudo[3513]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo systemd[1]: shairport-sync.service: Succeeded. Apr 18 14:30:02 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 18 14:30:02 primo volumio[3531]: vollibrespot v0.2.5 8922128 2022-05-31 (librespot 08d8bcc 2020-10-07) -- Built On 2022-05-31 Apr 18 14:30:02 primo volumio[3531]: Reading Config from "/tmp/volspotify.toml" Apr 18 14:30:02 primo volumio[3531]: [Vollibrespot] : Using Alsa backend with device: volumio Apr 18 14:30:02 primo sudo[3519]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 18 14:30:02 primo sudo[3516]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 18 14:30:02 primo volumio[780]: [SpotifyConnect] Vollibrespot Daemon service restarted! Apr 18 14:30:02 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 18 14:30:02 primo sudo[3529]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:02 primo volumio[780]: info: Shairport-Sync Started Apr 18 14:30:02 primo volumio[3531]: [Vollibrespot] : Failed to register IPv6 receiver: Os { code: 19, kind: Uncategorized, message: "No such device" } Apr 18 14:30:03 primo volumio[3531]: [Vollibrespot] : Connecting to AP "ap-gae2.spotify.com:443" Apr 18 14:30:04 primo volumio[3531]: [Vollibrespot] : Could not connect to server: bytes remaining on stream Apr 18 14:30:04 primo mpd[3524]: Apr 18 14:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 18 14:30:04 primo systemd[1]: Started Music Player Daemon. Apr 18 14:30:04 primo sudo[3498]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:04 primo volumio[780]: error: updateQueue error: null Apr 18 14:30:04 primo volumio[780]: info: TidalConnect service stoped! Apr 18 14:30:05 primo volumio[780]: info: TidalConnect service stoped! Apr 18 14:30:05 primo volumio[780]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 18 14:30:05 primo volumio[780]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 18 14:30:05 primo sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 18 14:30:05 primo sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:05 primo systemd[1]: Started Volumio Tidal Connect Service. Apr 18 14:30:05 primo sudo[3553]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:05 primo volumio[780]: info: Executing endpoint tc_getconfig Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Apr 18 14:30:05 primo vtcs[3555]: STARTING TidalConnect services, version: 1.5.0.45 Apr 18 14:30:05 primo vtcs[3555]: STARTED TidalConnect services. Apr 18 14:30:05 primo volumio[780]: info: Executing endpoint tc_connect Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Apr 18 14:30:05 primo volumio[780]: info: Connecting to TidalConnect Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::servicePushState Apr 18 14:30:05 primo volumio[780]: info: CoreStateMachine::pushState Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioPushState Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output update for this device Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output Apr 18 14:30:05 primo volumio[780]: info: CorePlayQueue::getTrack 0 Apr 18 14:30:05 primo volumio[780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::servicePushState Apr 18 14:30:05 primo volumio[780]: info: CoreStateMachine::pushState Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioPushState Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output update for this device Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output Apr 18 14:30:05 primo volumio[780]: info: CorePlayQueue::getTrack 0 Apr 18 14:30:05 primo volumio[780]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Apr 18 14:30:05 primo volumio[780]: info: TidalConnect service stoped! Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 18 14:30:05 primo volumio[780]: info: CoreStateMachine::getcurrentVolume Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 14:30:05 primo volumio[780]: info: TidalConnect service stoped! Apr 18 14:30:05 primo volumio[780]: info: CoreStateMachine::pushState Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioPushState Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output update for this device Apr 18 14:30:05 primo volumio[780]: info: MRS: Pushing multiroomSync output Apr 18 14:30:05 primo volumio[780]: info: CoreStateMachine::updateTrackBlock Apr 18 14:30:05 primo volumio[780]: info: CorePlayQueue::getTrackBlock Apr 18 14:30:05 primo volumio[780]: info: CoreCommandRouter::volumioRetrievevolume Apr 18 14:30:05 primo volumio[780]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Apr 18 14:30:05 primo volumio[780]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Apr 18 14:30:06 primo volumio[780]: info: CoreStateMachine::pushState Apr 18 14:30:06 primo volumio[780]: info: CoreCommandRouter::volumioPushState Apr 18 14:30:06 primo volumio[780]: info: MRS: Pushing multiroomSync output update for this device Apr 18 14:30:06 primo volumio[780]: info: MRS: Pushing multiroomSync output Apr 18 14:30:06 primo sudo[3595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Apr 18 14:30:06 primo sudo[3595]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 18 14:30:06 primo sudo[3595]: pam_unix(sudo:session): session closed for user root Apr 18 14:30:08 primo volumio[780]: info: Checking Spotify Web API Apr 18 14:30:08 primo volumio[780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 14:30:08 primo volumio[780]: Error [ERR_SOCKET_DGRAM_NOT_RUNNING]: Not running Apr 18 14:30:08 primo volumio[780]: at healthCheck (dgram.js:897:11) Apr 18 14:30:08 primo volumio[780]: at Socket.send (dgram.js:622:3) Apr 18 14:30:08 primo volumio[780]: at SpotConnEvents.sendmsg (/data/plugins/music_service/spop/SpotConnController.js:121:21) Apr 18 14:30:08 primo volumio[780]: at ControllerSpotify.checkWebApi (/data/plugins/music_service/spop/index.js:2383:23) Apr 18 14:30:08 primo volumio[780]: at Timeout._onTimeout (/data/plugins/music_service/spop/index.js:2723:18) Apr 18 14:30:08 primo volumio[780]: at listOnTimeout (internal/timers.js:554:17) Apr 18 14:30:08 primo volumio[780]: at processTimers (internal/timers.js:497:7) { Apr 18 14:30:08 primo volumio[780]: code: 'ERR_SOCKET_DGRAM_NOT_RUNNING' Apr 18 14:30:08 primo volumio[780]: } Apr 18 14:30:08 primo volumio[780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 18 14:30:08 primo sudo[3608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-18 14:29 Apr 18 14:30:08 primo sudo[3608]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"