Jul 10 15:10:43 volumio sudo[19027]: root : PWD=/srv/node/audio-rt-tester ; USER=root ; COMMAND=/usr/bin/jitterdebugger -a 0-3 -l 30000 -v Jul 10 15:10:43 volumio sudo[19027]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 10 15:10:43 volumio systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 10 15:10:44 volumio dbus-daemon[793]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.215' (uid=0 pid=19031 comm="timedatectl show --property=NTPSynchronized --valu") Jul 10 15:10:44 volumio systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 10 15:10:44 volumio dbus-daemon[793]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 10 15:10:44 volumio systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 10 15:10:44 volumio setdatetime-helper.sh[19029]: Time is already synchronized. Jul 10 15:10:44 volumio systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 10 15:10:44 volumio systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 10 15:10:50 volumio sudo[19063]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 15:10:50 volumio sudo[19063]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:10:50 volumio sudo[19063]: pam_unix(sudo:session): session closed for user root Jul 10 15:10:50 volumio sudo[19065]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 15:10:50 volumio sudo[19065]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:10:50 volumio sudo[19065]: pam_unix(sudo:session): session closed for user root Jul 10 15:10:50 volumio volumio[1113]: verbose: New Socket.io Connection to 192.168.101.72 from 192.168.101.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jul 10 15:10:51 volumio sudo[19071]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 10 15:10:51 volumio sudo[19071]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:10:51 volumio sudo[19071]: pam_unix(sudo:session): session closed for user root Jul 10 15:10:51 volumio sudo[19073]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 10 15:10:51 volumio sudo[19073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:10:51 volumio sudo[19073]: pam_unix(sudo:session): session closed for user root Jul 10 15:10:51 volumio volumio[1113]: verbose: New Socket.io Connection to 192.168.101.72 from 192.168.101.2 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/138.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:10:51 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 10 15:10:51 volumio volumio[1113]: error: Could not retrieve plugin audio_interface multiroom Jul 10 15:10:51 volumio volumio[1113]: info: Listing playlists Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 10 15:10:51 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 15:10:56 volumio volumio[1113]: info: CoreCommandRouter::volumioPlay Jul 10 15:10:56 volumio volumio[1113]: info: CoreStateMachine::play index undefined Jul 10 15:10:56 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService undefined Jul 10 15:10:56 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:10:56 volumio volumio[1113]: info: CoreStateMachine::startPlaybackTimer Jul 10 15:10:56 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:10:56 volumio volumio[1113]: error: Could not retrieve plugin miscellanea metavolumio Jul 10 15:10:56 volumio volumio[1113]: info: [1752160256966] ControllerWebradio::clearAddPlayTrack Jul 10 15:10:56 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand stop Jul 10 15:10:56 volumio volumio[1113]: info: sendMpdCommand stop took 2 milliseconds Jul 10 15:10:56 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clear Jul 10 15:10:56 volumio volumio[1113]: info: Jul 10 15:10:56 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:10:56 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:10:56 volumio volumio[1113]: info: sendMpdCommand clear took 2 milliseconds Jul 10 15:10:56 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand load "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Jul 10 15:10:56 volumio volumio[1113]: info: Jul 10 15:10:56 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:10:56 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:10:56 volumio volumio[1113]: info: Jul 10 15:10:56 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:10:56 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:10:56 volumio volumio[1113]: error: updateQueue error: null Jul 10 15:10:56 volumio volumio[1113]: info: ------------------------------ 8ms Jul 10 15:11:02 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" Jul 10 15:11:02 volumio volumio[1113]: error: updateQueue error: null Jul 10 15:11:02 volumio volumio[1113]: error: updateQueue error: null Jul 10 15:11:02 volumio volumio[1113]: info: Jul 10 15:11:02 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:11:02 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:11:02 volumio volumio[1113]: info: ------------------------------ 5293ms Jul 10 15:11:02 volumio volumio[1113]: info: ------------------------------ 5292ms Jul 10 15:11:02 volumio volumio[1113]: info: sendMpdCommand add "https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3" took 3 milliseconds Jul 10 15:11:02 volumio volumio[1113]: info: CoreStateMachine::setConsumeUpdateService mpd Jul 10 15:11:02 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand play Jul 10 15:11:02 volumio volumio[1113]: info: Jul 10 15:11:02 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:11:02 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:11:02 volumio volumio[1113]: info: Jul 10 15:11:02 volumio volumio[1113]: ---------------------------- MPD announces system playlist update Jul 10 15:11:02 volumio volumio[1113]: info: Ignoring MPD Status Update Jul 10 15:11:02 volumio volumio[1113]: info: ------------------------------ 7ms Jul 10 15:11:02 volumio volumio[1113]: info: sendMpdCommand play took 5 milliseconds Jul 10 15:11:02 volumio volumio[1113]: info: ------------------------------ 5ms Jul 10 15:11:02 volumio volumio[1113]: info: ------------------------------ 4ms Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 15 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 23 milliseconds Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand clearerror Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 22 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand clearerror took 11 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 10 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 9 milliseconds Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 48ms Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: info: Jul 10 15:11:06 volumio volumio[1113]: ---------------------------- MPD announces state update: player Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::getState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand status Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand clearerror took 36 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand clearerror took 36 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 29 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 30 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 8 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand status took 7 milliseconds Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseState Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 85ms Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 83ms Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 78ms Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 10 15:11:06 volumio volumio[1113]: info: sendMpdCommand playlistinfo took 40 milliseconds Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: verbose: ControllerMpd::parseTrackInfo Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ControllerMpd::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::servicePushState Jul 10 15:11:06 volumio volumio[1113]: info: CorePlayQueue::getTrack 0 Jul 10 15:11:06 volumio volumio[1113]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"mp3","artist":null,"album":null,"uri":"https://absolut-ai.live-sm.absolutradio.de/absolut-ai/stream/mp3","trackType":""} Jul 10 15:11:06 volumio volumio[1113]: verbose: CURRENT POSITION 0 Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState stateService pause Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::syncState currentStatus stop Jul 10 15:11:06 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 71ms Jul 10 15:11:06 volumio volumio[1113]: info: ------------------------------ 71ms Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:06 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 15:11:07 volumio volumio[1113]: Invalid card number '5'. Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 15:11:07 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 10 15:11:09 volumio volumio[1113]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 10 15:11:09 volumio volumio[1113]: error: Could not retrieve plugin system_controller my_volumio Jul 10 15:11:09 volumio volumio[1113]: info: Received Get System Version Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 10 15:11:09 volumio volumio[1113]: info: Received Get System Info Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 10 15:11:09 volumio volumio[1113]: info: Discovery: Getting this device information Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:09 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 10 15:11:14 volumio sudo[19027]: pam_unix(sudo:session): session closed for user root Jul 10 15:11:14 volumio systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 10 15:11:24 volumio kernel: usb 1-1.1.2: new full-speed USB device number 8 using ehci-pci Jul 10 15:11:24 volumio kernel: usb 1-1.1.2: New USB device found, idVendor=0a12, idProduct=1005, bcdDevice=36.05 Jul 10 15:11:24 volumio kernel: usb 1-1.1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=3 Jul 10 15:11:24 volumio kernel: usb 1-1.1.2: Product: REIYIN Bluetooth Audio WT- HD05 Jul 10 15:11:24 volumio kernel: usb 1-1.1.2: SerialNumber: ABCDEF0123456789 Jul 10 15:11:24 volumio kernel: input: REIYIN Bluetooth Audio WT- HD05 Consumer Control as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.3/0003:0A12:1005.0006/input/input11 Jul 10 15:11:24 volumio kernel: input: REIYIN Bluetooth Audio WT- HD05 as /devices/pci0000:00/0000:00:1d.0/usb1/1-1/1-1.1/1-1.1.2/1-1.1.2:1.3/0003:0A12:1005.0006/input/input12 Jul 10 15:11:24 volumio kernel: hid-generic 0003:0A12:1005.0006: input,hiddev1,hidraw5: USB HID v1.11 Device [REIYIN Bluetooth Audio WT- HD05] on usb-0000:00:1d.0-1.1.2/input3 Jul 10 15:11:24 volumio kernel: hid-generic 0003:0A12:1005.0007: hiddev2,hidraw6: USB HID v1.11 Device [REIYIN Bluetooth Audio WT- HD05] on usb-0000:00:1d.0-1.1.2/input4 Jul 10 15:11:24 volumio (udev-worker)[19186]: event11: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 15:11:24 volumio (udev-worker)[19187]: event12: Process '/usr/sbin/th-cmd --socket /var/run/thd.socket --passfd --udev' failed with exit code 1. Jul 10 15:11:24 volumio kernel: usbcore: registered new interface driver snd-usb-audio Jul 10 15:11:24 volumio systemd[1440]: Reached target sound.target - Sound Card. Jul 10 15:11:24 volumio volumio[1113]: info: No valid Plugin REST Endpoint Jul 10 15:11:24 volumio volumio[1113]: info: Jul 10 15:11:24 volumio volumio[1113]: ---------------------------- USB Audio Device Attached Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::Close All Modals sent Jul 10 15:11:24 volumio volumio[1113]: info: Preparing to save Alsa Options, stopping services first Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:24 volumio volumio[1113]: info: Saving Audio Output to: {"disallowPush":true,"output_device":{"value":"5","label":"REIYIN Bluetooth Audio WT- HD05","alsacard":"HD05"},"i2s":false} Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 15:11:24 volumio volumio[1113]: info: Setting mixer PCM for card REIYIN Bluetooth Audio WT- HD05 Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jul 10 15:11:24 volumio volumio[1113]: info: Updating Volume Controller Parameters: Device: 5 Name: REIYIN Bluetooth Audio WT- HD05 Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jul 10 15:11:24 volumio volumio[1113]: info: Disabling external Volume Control Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 10 15:11:24 volumio volumio[1113]: info: Preparing to generate the ALSA configuration file Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jul 10 15:11:24 volumio volumio[1113]: info: Asound.conf file unchanged, so no further update is needed Jul 10 15:11:24 volumio volumio[1113]: info: Output device has changed, restarting MPD Jul 10 15:11:24 volumio volumio[1113]: info: Output device has changed, restarting Shairport Sync Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 15:11:24 volumio sudo[19235]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 10 15:11:24 volumio sudo[19235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:11:24 volumio volumio[1113]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 10 15:11:24 volumio sudo[19232]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 10 15:11:24 volumio sudo[19232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:11:24 volumio volumio[1113]: info: VolumeController:: Volume=0 Mute =false Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:24 volumio volumio[1113]: info: CoreStateMachine::pushState Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::volumioPushState Jul 10 15:11:24 volumio sudo[19232]: pam_unix(sudo:session): session closed for user root Jul 10 15:11:24 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 10 15:11:24 volumio volumio[1113]: info: MPD Permissions set Jul 10 15:11:24 volumio volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 10 15:11:24 volumio volumio[1113]: info: Starting Shairport Sync Jul 10 15:11:24 volumio sudo[19251]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 10 15:11:24 volumio sudo[19251]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 10 15:11:25 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Jul 10 15:11:25 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Jul 10 15:11:25 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 10 15:11:25 volumio systemd[1]: shairport-sync.service: Consumed 7.053s CPU time. Jul 10 15:11:25 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Jul 10 15:11:25 volumio sudo[19251]: pam_unix(sudo:session): session closed for user root Jul 10 15:11:25 volumio volumio[1113]: info: Shairport-Sync Started Jul 10 15:11:25 volumio volumio[1113]: info: CoreCommandRouter::executeOnPlugin: raat , restartRaat Jul 10 15:11:25 volumio volumio[1113]: error: Could not retrieve plugin music_service raat Jul 10 15:11:25 volumio systemd[1]: mpd.service: Deactivated successfully. Jul 10 15:11:25 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jul 10 15:11:25 volumio systemd[1]: mpd.service: Consumed 8.931s CPU time. Jul 10 15:11:25 volumio systemd[1]: mpd.socket: Deactivated successfully. Jul 10 15:11:25 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jul 10 15:11:25 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jul 10 15:11:25 volumio volumio[1113]: error: [ytcr] Error connecting MPD: Jul 10 15:11:25 volumio volumio[1113]: (Error) read ECONNRESET Jul 10 15:11:25 volumio volumio[1113]: Error: read ECONNRESET Jul 10 15:11:25 volumio volumio[1113]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Jul 10 15:11:25 volumio volumio[1113]: Retrying in 5 seconds... Jul 10 15:11:25 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jul 10 15:11:25 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jul 10 15:11:25 volumio sudo[19269]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 10 15:11:25 volumio sudo[19269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 10 15:11:25 volumio sudo[19269]: pam_unix(sudo:session): session closed for user root Jul 10 15:11:26 volumio volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:11:26 volumio volumio[1113]: TypeError: Cannot read properties of null (reading 'logger') Jul 10 15:11:26 volumio volumio[1113]: at SqueezeliteMCContext.getLogger (/data/plugins/music_service/squeezelite_mc/dist/lib/SqueezeliteMCContext.js:65:86) Jul 10 15:11:26 volumio volumio[1113]: at ControllerSqueezeliteMC._ControllerSqueezeliteMC_stdLogError (/data/plugins/music_service/squeezelite_mc/dist/index.js:716:36) Jul 10 15:11:26 volumio volumio[1113]: at /data/plugins/music_service/squeezelite_mc/dist/index.js:1216:121 Jul 10 15:11:26 volumio volumio[1113]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 10 15:11:26 volumio volumio[1113]: at runNextTicks (node:internal/process/task_queues:64:3) Jul 10 15:11:26 volumio volumio[1113]: at listOnTimeout (node:internal/timers:540:9) Jul 10 15:11:26 volumio volumio[1113]: at process.processTimers (node:internal/timers:514:7) Jul 10 15:11:26 volumio volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 10 15:11:26 volumio sudo[19289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-10 15:10' Jul 10 15:11:26 volumio sudo[19289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="9807089f93a51a36bddd01d7490608fa210b1ca5" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 30 Jun 19:35:51 CEST 2025" VOLUMIO_VERSION="0.001" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="f32bdcb50f8f6aeb31a0dce71e64e55a"