-- Logs begin at Tue 2024-10-01 20:34:36 CEST, end at Tue 2024-10-01 20:38:13 CEST. -- Oct 01 20:37:04 volumio kernel: usb 3-1: USB disconnect, device number 2 Oct 01 20:37:04 volumio volumio[952]: info: Oct 01 20:37:04 volumio volumio[952]: ---------------------------- USB Audio Device Detached Oct 01 20:37:04 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Oct 01 20:37:04 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:37:04 volumio volumio[952]: aplay: device_list:272: no soundcards found... Oct 01 20:37:04 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: raat , onStop Oct 01 20:37:04 volumio thd[668]: Error reading device '/dev/input/event3' Oct 01 20:37:04 volumio volumio[952]: info: No valid Plugin REST Endpoint: scanAudioInputs Oct 01 20:37:04 volumio systemd[1]: Stopped target Sound Card. Oct 01 20:37:12 volumio kernel: usb 3-1: new full-speed USB device number 3 using xhci_hcd Oct 01 20:37:12 volumio kernel: usb 3-1: device descriptor read/64, error -71 Oct 01 20:37:12 volumio kernel: usb 3-1: device descriptor read/64, error -71 Oct 01 20:37:12 volumio kernel: usb 3-1: new full-speed USB device number 4 using xhci_hcd Oct 01 20:37:12 volumio kernel: usb 3-1: device descriptor read/64, error -71 Oct 01 20:37:13 volumio kernel: usb 3-1: device descriptor read/64, error -71 Oct 01 20:37:13 volumio kernel: usb usb3-port1: attempt power cycle Oct 01 20:37:13 volumio kernel: usb 3-1: new high-speed USB device number 5 using xhci_hcd Oct 01 20:37:14 volumio kernel: usb 3-1: New USB device found, idVendor=28d4, idProduct=0008, bcdDevice= 6.f2 Oct 01 20:37:14 volumio kernel: usb 3-1: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Oct 01 20:37:14 volumio kernel: usb 3-1: Product: Expert Pro USB Audio 2.0 Oct 01 20:37:14 volumio kernel: usb 3-1: Manufacturer: DEVIALET Oct 01 20:37:15 volumio kernel: input: DEVIALET Expert Pro USB Audio 2.0 as /devices/pci0000:00/0000:00:1c.0/0000:02:00.0/usb3/3-1/3-1:1.4/0003:28D4:0008.0003/input/input6 Oct 01 20:37:15 volumio kernel: hid-generic 0003:28D4:0008.0003: input,hidraw0: USB HID v1.10 Device [DEVIALET Expert Pro USB Audio 2.0] on usb-0000:02:00.0-1/input4 Oct 01 20:37:15 volumio systemd[1]: Reached target Sound Card. Oct 01 20:37:15 volumio volumio[952]: info: Oct 01 20:37:15 volumio volumio[952]: ---------------------------- USB Audio Device Attached Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::Close All Modals sent Oct 01 20:37:15 volumio volumio[952]: info: Preparing to save Alsa Options, stopping services first Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:37:15 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::volumioPause Oct 01 20:37:15 volumio volumio[952]: info: CoreStateMachine::pause Oct 01 20:37:15 volumio volumio[952]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"Expert Pro USB Audio 2.0","alsacard":"E20"},"i2s":false} Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:37:15 volumio volumio[952]: info: Setting mixer Expert Pro USB Audio 2.0 for card Expert Pro USB Audio 2.0 Oct 01 20:37:15 volumio vtcs[1630]: [2024-10-01 20:37:15.504] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 01 20:37:15 volumio vtcs[1630]: [2024-10-01 20:37:15.504] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 01 20:37:15 volumio sudo[1771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 01 20:37:15 volumio sudo[1771]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 01 20:37:15 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 01 20:37:15 volumio systemd[1]: vtcs.service: Succeeded. Oct 01 20:37:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 01 20:37:15 volumio sudo[1771]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 01 20:37:15 volumio volumio[952]: info: Updating Volume Controller Parameters: Device: 5 Name: Expert Pro USB Audio 2.0 Mixer: Expert Pro USB Audio 2.0 Max Vol: 80 Vol Curve; logarithmic Vol Steps: 1 Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 01 20:37:15 volumio volumio[952]: info: Disabling external Volume Control Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:37:15 volumio volumio[952]: info: Preparing to generate the ALSA configuration file Oct 01 20:37:15 volumio sudo[1780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 01 20:37:15 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: info: No valid Plugin REST Endpoint: scanAudioInputs Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 01 20:37:15 volumio systemd-udevd[1754]: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 20:37:15 volumio volumio[952]: info: Asound.conf file unchanged, so no further update is needed Oct 01 20:37:15 volumio volumio[952]: info: Output device has changed, restarting MPD Oct 01 20:37:15 volumio sudo[1789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 01 20:37:15 volumio sudo[1789]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio sudo[1789]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: info: Output device has changed, restarting Shairport Sync Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 20:37:15 volumio sudo[1792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 01 20:37:15 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio systemd[1]: Stopping Music Player Daemon... Oct 01 20:37:15 volumio systemd[1]: mpd.service: Succeeded. Oct 01 20:37:15 volumio systemd[1]: Stopped Music Player Daemon. Oct 01 20:37:15 volumio volumio[952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 01 20:37:15 volumio volumio[952]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 01 20:37:15 volumio sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio systemd[1]: Starting Music Player Daemon... Oct 01 20:37:15 volumio volumio[952]: info: MPD Permissions set Oct 01 20:37:15 volumio volumio[952]: info: VolumeController:: Volume=73 Mute =false Oct 01 20:37:15 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:15 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:15 volumio sudo[1804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 01 20:37:15 volumio sudo[1804]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:15 volumio volumio[952]: SPOTIFY: SPOTIFY VOLUME 75 Oct 01 20:37:15 volumio volumio[952]: SPOTIFY: VOLUMIO VOLUME 73 Oct 01 20:37:15 volumio volumio[952]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 01 20:37:15 volumio volumio[952]: info: Setting Spotify Volume from Volumio: 73 Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio sudo[1804]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio sudo[1801]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 01 20:37:15 volumio sudo[1797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 01 20:37:15 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 01 20:37:15 volumio sudo[1797]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio volumio[952]: info: Starting Shairport Sync Oct 01 20:37:15 volumio sudo[1797]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio sudo[1817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 01 20:37:15 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:15 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 01 20:37:15 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 01 20:37:15 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 01 20:37:15 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 01 20:37:15 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: info: Shairport-Sync Started Oct 01 20:37:15 volumio mpd[1815]: Oct 01 20:37 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 01 20:37:15 volumio systemd[1]: Started Music Player Daemon. Oct 01 20:37:15 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:15 volumio volumio[952]: error: updateQueue error: null Oct 01 20:37:16 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Oct 01 20:37:17 volumio volumio[952]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Oct 01 20:37:17 volumio volumio[952]: info: Sending Spotify command with payload to local API: /player/volume Oct 01 20:37:17 volumio go-librespot[1516]: time="2024-10-01T20:37:17+02:00" level=debug msg="update volume to 47840/65535" Oct 01 20:37:17 volumio go-librespot[1516]: time="2024-10-01T20:37:17+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 01 20:37:17 volumio go-librespot[1516]: time="2024-10-01T20:37:17+02:00" level=trace msg="emitting websocket event: volume" Oct 01 20:37:17 volumio volumio[952]: SPOTIFY: received: {"type":"volume","data":{"value":73,"max":100}} Oct 01 20:37:17 volumio volumio[952]: SPOTIFY: RECEIVED SPOTIFY VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: info: TidalConnect service stoped! Oct 01 20:37:18 volumio volumio[952]: info: TidalConnect service stoped! Oct 01 20:37:18 volumio volumio[952]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 01 20:37:18 volumio volumio[952]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 01 20:37:18 volumio sudo[1833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 01 20:37:18 volumio sudo[1833]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:18 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 01 20:37:18 volumio sudo[1833]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:18 volumio volumio[952]: info: Executing endpoint tc_getconfig Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 01 20:37:18 volumio vtcs[1835]: STARTING TidalConnect services, version: 1.4.0.34 Oct 01 20:37:18 volumio volumio[952]: info: TidalConnect service stoped! Oct 01 20:37:18 volumio volumio[952]: info: TidalConnect service stoped! Oct 01 20:37:18 volumio vtcs[1835]: STARTED TidalConnect services. Oct 01 20:37:18 volumio volumio[952]: info: Executing endpoint tc_connect Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 01 20:37:18 volumio volumio[952]: info: Connecting to TidalConnect Oct 01 20:37:18 volumio volumio[952]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 01 20:37:18 volumio volumio[952]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::servicePushState Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: 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} Oct 01 20:37:18 volumio volumio[952]: verbose: CURRENT POSITION 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::syncState stateService stop Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::syncState currentStatus stop Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: info: No code Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::servicePushState Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: 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} Oct 01 20:37:18 volumio volumio[952]: verbose: CURRENT POSITION 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::syncState stateService stop Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::syncState currentStatus stop Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: info: No code Oct 01 20:37:18 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:18 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:18 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:18 volumio sudo[1863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 01 20:37:18 volumio sudo[1863]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:37:18 volumio sudo[1863]: pam_unix(sudo:session): session closed for user root Oct 01 20:37:19 volumio volumio[952]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 01 20:37:19 volumio volumio[952]: info: CoreStateMachine::getcurrentVolume Oct 01 20:37:19 volumio volumio[952]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 20:37:19 volumio volumio[952]: info: VolumeController:: Volume=73 Mute =false Oct 01 20:37:19 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:19 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:19 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:19 volumio volumio[952]: info: CoreStateMachine::updateTrackBlock Oct 01 20:37:19 volumio volumio[952]: info: CorePlayQueue::getTrackBlock Oct 01 20:37:19 volumio volumio[952]: info: CoreCommandRouter::volumioRetrievevolume Oct 01 20:37:19 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:19 volumio volumio[952]: info: VolumeController:: Volume=73 Mute =false Oct 01 20:37:19 volumio volumio[952]: info: CoreStateMachine::pushState Oct 01 20:37:19 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:19 volumio volumio[952]: info: CoreCommandRouter::volumioPushState Oct 01 20:37:19 volumio volumio[952]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 01 20:37:21 volumio volumio[952]: info: TidalConnect service started! Oct 01 20:37:21 volumio volumio[952]: info: TidalConnect service started! Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:37:58 volumio volumio[952]: info: Discovery: Getting this device information Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:37:58 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:37:58 volumio volumio[952]: verbose: New Socket.io Connection to 192.168.1.143:3000 from 192.168.1.20 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 6 Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:37:58 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Oct 01 20:37:58 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Oct 01 20:38:02 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 20:38:02 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:38:02 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root Oct 01 20:38:02 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 20:38:02 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:38:02 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Oct 01 20:38:02 volumio volumio[952]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Oct 01 20:38:02 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 01 20:38:02 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 01 20:38:03 volumio sudo[1919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 01 20:38:03 volumio sudo[1919]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:38:03 volumio sudo[1919]: pam_unix(sudo:session): session closed for user root Oct 01 20:38:03 volumio sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 01 20:38:03 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:38:03 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root Oct 01 20:38:03 volumio volumio[952]: verbose: New Socket.io Connection to 192.168.1.143 from 192.168.1.20 UA: Mozilla/5.0 (Linux; Android 10; CLT-L29 Build/HUAWEICLT-L29; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/128.0.6613.146 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:38:03 volumio volumio[952]: info: Discovery: Getting this device information Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:03 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::volumioGetVisibleSources Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:03 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 01 20:38:03 volumio volumio[952]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 01 20:38:03 volumio volumio[952]: info: Received Get System Info Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:38:03 volumio volumio[952]: info: Discovery: Getting this device information Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:03 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:03 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:03 volumio volumio[952]: info: Listing playlists Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 01 20:38:03 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 01 20:38:05 volumio volumio[952]: info: Received Get System Info Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:38:05 volumio volumio[952]: info: Discovery: Getting this device information Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:05 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:38:05 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 01 20:38:06 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 01 20:38:06 volumio volumio[952]: info: Received Get System Info Oct 01 20:38:06 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 01 20:38:06 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 01 20:38:06 volumio volumio[952]: info: Discovery: Getting this device information Oct 01 20:38:06 volumio volumio[952]: info: CoreCommandRouter::volumioGetState Oct 01 20:38:06 volumio volumio[952]: info: CorePlayQueue::getTrack 0 Oct 01 20:38:06 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: Retrieving Cloud Streaming UI Oct 01 20:38:08 volumio volumio[952]: info: Getting Tidal Cloud Configuration Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: Getting Qobuz Cloud Configuration Oct 01 20:38:08 volumio volumio[952]: info: Asking plugin for UI Config Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: Getting Spotify Cloud Configuration Oct 01 20:38:08 volumio volumio[952]: info: Asking plugin for UI Config Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: Saving Spotify Acccount Oct 01 20:38:08 volumio volumio[952]: info: Got it Oct 01 20:38:08 volumio volumio[952]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 01 20:38:08 volumio volumio[952]: info: Got Tidal Cloud Configuration Oct 01 20:38:08 volumio volumio[952]: info: Got it Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::volumioGetBrowseSources Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 20:38:08 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 01 20:38:11 volumio volumio[952]: info: Disabling MyMusic plugin upnp Oct 01 20:38:11 volumio sudo[1927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 01 20:38:11 volumio sudo[1927]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 01 20:38:11 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Oct 01 20:38:11 volumio volumio[952]: error: Upnp client error: Error: This socket has been ended by the other party Oct 01 20:38:12 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 01 20:38:13 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 01 20:38:13 volumio volumio[952]: info: Enabling MyMusic plugin upnp Oct 01 20:38:13 volumio volumio[952]: info: Enabling plugin upnp Oct 01 20:38:13 volumio volumio[952]: info: Loading plugin "upnp"... Oct 01 20:38:13 volumio volumio[952]: info: [1727807893321] Starting Upmpd Daemon Oct 01 20:38:13 volumio volumio[952]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 01 20:38:13 volumio volumio[952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 20:38:13 volumio volumio[952]: Error: listen EADDRINUSE: address already in use :::6599 Oct 01 20:38:13 volumio volumio[952]: at Server.setupListenHandle [as _listen2] (net.js:1331:16) Oct 01 20:38:13 volumio volumio[952]: at listenInCluster (net.js:1379:12) Oct 01 20:38:13 volumio volumio[952]: at Server.listen (net.js:1465:7) Oct 01 20:38:13 volumio volumio[952]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 01 20:38:13 volumio volumio[952]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 01 20:38:13 volumio volumio[952]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 01 20:38:13 volumio volumio[952]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 01 20:38:13 volumio volumio[952]: at processTicksAndRejections (internal/process/task_queues.js:77:11) { Oct 01 20:38:13 volumio volumio[952]: code: 'EADDRINUSE', Oct 01 20:38:13 volumio volumio[952]: errno: -98, Oct 01 20:38:13 volumio volumio[952]: syscall: 'listen', Oct 01 20:38:13 volumio volumio[952]: address: '::', Oct 01 20:38:13 volumio volumio[952]: port: 6599 Oct 01 20:38:13 volumio volumio[952]: } Oct 01 20:38:13 volumio volumio[952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 01 20:38:13 volumio sudo[1938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-01 20:37 Oct 01 20:38:13 volumio sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"