May 27 22:43:34 streamer ntpd[1187]: CLOCK: time stepped by 5570538.597437 May 27 22:43:34 streamer ntpd[1187]: CLOCK: time changed from 2026-03-24 to 2026-05-27 May 27 22:43:34 streamer ntpd[1187]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 27 22:43:34 streamer systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 27 22:43:34 streamer systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 27 22:43:34 streamer systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 27 22:43:34 streamer systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 27 22:43:34 streamer systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 27 22:43:36 streamer volumio[1750]: info: Discovery: A device disappeared from network May 27 22:43:36 streamer volumio[1750]: info: Discovery: Device streamer disappeared from network May 27 22:43:36 streamer volumio[1750]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 27 22:43:37 streamer volumio[1750]: info: Discovery: A device disappeared from network May 27 22:43:37 streamer volumio[1750]: info: Discovery: Device volumio disappeared from network May 27 22:43:37 streamer volumio[1750]: info: Discovery: Disconnected from remote: 192.168.1.138 May 27 22:43:38 streamer volumio[1750]: info: Discovery: A device disappeared from network May 27 22:43:38 streamer volumio[1750]: info: Discovery: Device garage disappeared from network May 27 22:43:38 streamer volumio[1750]: info: Discovery: Disconnected from remote: 192.168.1.151 May 27 22:43:38 streamer sudo[2109]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 27 22:43:38 streamer sudo[2109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:43:38 streamer sudo[2109]: pam_unix(sudo:session): session closed for user root May 27 22:43:38 streamer sudo[2111]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 27 22:43:38 streamer sudo[2111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:43:38 streamer sudo[2111]: pam_unix(sudo:session): session closed for user root May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 22:43:38 streamer volumio[1750]: info: camilladsp service started and running in background, instance 1 May 27 22:43:38 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 <-AAAAAAAAAAAAA May 27 22:43:38 streamer volumio[1750]: info: FusionDsp loaded May 27 22:43:38 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - --[object Object] May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - Reporting Fusion DSP Enabled May 27 22:43:38 streamer volumio[1750]: info: Adding Signal Path Element [object Object] May 27 22:43:38 streamer volumio[1750]: info: Adding fusiondspeq DSP Signal Path Element May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) May 27 22:43:38 streamer volumio[1750]: info: FusionDsp - PEQ graph server listening on port 10015 May 27 22:43:38 streamer sudo[2126]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 27 22:43:38 streamer sudo[2126]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:43:38 streamer systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... May 27 22:43:40 streamer volumio[1750]: info: Discovery: A device disappeared from network May 27 22:43:40 streamer systemd[1]: systemd-fsckd.service: Deactivated successfully. May 27 22:43:41 streamer volumio[1750]: error: FusionDsp - Reload WebSocket error: [object Object] May 27 22:43:41 streamer dhcpcd[919]: timed out May 27 22:43:41 streamer sh[895]: timed out May 27 22:43:41 streamer sh[835]: ifup: failed to bring up eth0 May 27 22:43:41 streamer systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE May 27 22:43:41 streamer systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. May 27 22:43:43 streamer systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 27 22:43:43 streamer systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... May 27 22:43:43 streamer systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 27 22:43:43 streamer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 27 22:43:43 streamer systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 27 22:43:43 streamer systemd[1]: e2scrub_all.service: Deactivated successfully. May 27 22:43:43 streamer systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. May 27 22:43:43 streamer upmpdcli[2137]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 192.168.1.225 port 49152 IPV6 port 49152 May 27 22:43:43 streamer upmpdcli[2137]: :3:../src/mediaserver/cdplugins/plgwithslave.cxx:248::PlgWithSlave: starting streaming proxy on port 49149 May 27 22:43:43 streamer systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 27 22:43:43 streamer sudo[2126]: pam_unix(sudo:session): session closed for user root May 27 22:43:47 streamer volumio[1750]: info: Discovery: adding 6f0262f5-7ca1-4fc4-91ee-6eec4ec75b4c May 27 22:43:47 streamer volumio[1750]: info: Discovery: Found device streamer May 27 22:43:47 streamer volumio[1750]: info: CoreCommandRouter::volumioGetState May 27 22:43:47 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:47 streamer volumio[1750]: info: Discovery: this is already registered, 6f0262f5-7ca1-4fc4-91ee-6eec4ec75b4c May 27 22:43:47 streamer volumio[1750]: info: Discovery: Found device streamer May 27 22:43:47 streamer volumio[1750]: info: CoreCommandRouter::volumioGetState May 27 22:43:47 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:49 streamer shairport-sync[2174]: % May 27 22:43:49 streamer shairport-sync[2175]: % Total % Received % Xferd Average Speed Time Time Time Current May 27 22:43:49 streamer shairport-sync[2175]: Dload Upload Total Spent Left Speed May 27 22:43:49 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:49 streamer shairport-sync[2174]: Total % Received % Xferd Average Speed Time Time Time Current May 27 22:43:49 streamer shairport-sync[2174]: Dload Upload Total Spent Left Speed May 27 22:43:49 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:49 streamer shairport-sync[2046]: ALSA lib /home/pi/volumio-hook-plugin/src/pcm_volumiohook.c:101:(_snd_pcm_volumiohook_execute) The PCM fusiondsphook failed to run command echo '44100,S32_LE,2,32' >/tmp/fusiondsp_stream_params.log May 27 22:43:49 streamer volumio[1750]: info: Discovery: adding d6536d47-57f1-4a23-8abe-649453d4b880 May 27 22:43:49 streamer volumio[1750]: info: Discovery: Found device Volumio May 27 22:43:49 streamer volumio[1750]: info: Discovery: Connecting to remote: 192.168.1.138 May 27 22:43:49 streamer volumio[1750]: verbose: New Socket.io Connection to 192.168.1.225:3000 from 192.168.1.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 27 22:43:50 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:51 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:51 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:52 streamer upmpdcli[2190]: writing RSA key May 27 22:43:52 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:52 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:52 streamer volumio[1750]: info: Airplay started streaming, receiving metadatas May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioStop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::stop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: Airplay started streaming, receiving metadatas May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioStop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::stop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: Airplay started streaming, receiving metadatas May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioStop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::stop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: Airplay started streaming, receiving metadatas May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioStop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::stop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: Airplay started streaming, receiving metadatas May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioStop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::stop May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus stop May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"","bitdepth":"","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Rhapsody in Blue","artist":"Leonard Bernstein & Columbia Symphony Orchestra","album":"Gershwin: Rhapsody in Blue - An American in Paris","albumart":"/albumart?cacheid=918&web=Leonard%20Bernstein%20%26%20Columbia%20Symphony%20Orchestra/Gershwin%3A%20Rhapsody%20in%20Blue%20-%20An%20American%20in%20Paris/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Rhapsody in Blue","artist":"Leonard Bernstein & Columbia Symphony Orchestra","album":"Gershwin: Rhapsody in Blue - An American in Paris","albumart":"/albumart?cacheid=918&web=Leonard%20Bernstein%20%26%20Columbia%20Symphony%20Orchestra/Gershwin%3A%20Rhapsody%20in%20Blue%20-%20An%20American%20in%20Paris/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Rhapsody in Blue","artist":"Leonard Bernstein & Columbia Symphony Orchestra","album":"Gershwin: Rhapsody in Blue - An American in Paris","albumart":"/albumart?cacheid=918&web=Leonard%20Bernstein%20%26%20Columbia%20Symphony%20Orchestra/Gershwin%3A%20Rhapsody%20in%20Blue%20-%20An%20American%20in%20Paris/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Rhapsody in Blue","artist":"Leonard Bernstein & Columbia Symphony Orchestra","album":"Gershwin: Rhapsody in Blue - An American in Paris","albumart":"/albumart?cacheid=918&web=Leonard%20Bernstein%20%26%20Columbia%20Symphony%20Orchestra/Gershwin%3A%20Rhapsody%20in%20Blue%20-%20An%20American%20in%20Paris/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: verbose: STATE SERVICE {"status":"play","service":"airplay_emulation","title":"Rhapsody in Blue","artist":"Leonard Bernstein & Columbia Symphony Orchestra","album":"Gershwin: Rhapsody in Blue - An American in Paris","albumart":"/albumart?cacheid=918&web=Leonard%20Bernstein%20%26%20Columbia%20Symphony%20Orchestra/Gershwin%3A%20Rhapsody%20in%20Blue%20-%20An%20American%20in%20Paris/extralarge&path=&metadata=false","uri":"","trackType":"airplay","seek":null,"duration":null,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"disableUiControls":true} May 27 22:43:52 streamer volumio[1750]: verbose: CURRENT POSITION 0 May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState stateService play May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::syncState currentStatus play May 27 22:43:52 streamer volumio[1750]: info: Received an update from plugin. extracting info from payload May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:52 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:52 streamer volumio[1750]: verbose: New Socket.io Connection to 192.168.1.225:3000 from 192.168.1.151 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 May 27 22:43:52 streamer volumio[1750]: info: camilladsp respawn in 1000 ms (attempt 1/10) May 27 22:43:52 streamer volumio[1750]: error: MyVolumio Plugin failed to start in a timely fashion May 27 22:43:52 streamer volumio[1750]: [Metrics] CommandRouter: 25s 9.41ms May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::volumiosetStartupVolume May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 22:43:52 streamer volumio[1750]: info: VolumeController:: Setting startup Volume 5 May 27 22:43:52 streamer volumio[1750]: info: VolumeController::SetAlsaVolume5 May 27 22:43:52 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::Close All Modals sent May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::Close All Modals sent May 27 22:43:53 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:53 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:53 streamer volumio[1750]: info: Updating volume from AirPlay: -20; 33% May 27 22:43:53 streamer volumio[1750]: info: VolumeController::SetAlsaVolume33 May 27 22:43:53 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:43:53 streamer volumio[1750]: info: CorePlayQueue::getTrack 0 May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 22:43:53 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:43:53 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:53 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:54 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:54 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:55 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:55 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:56 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:56 streamer shairport-sync[2175]: [73B blob data] May 27 22:43:56 streamer shairport-sync[2174]: May 27 22:43:56 streamer shairport-sync[2175]: May 27 22:43:56 streamer shairport-sync[2174]: May 27 22:43:56 streamer shairport-sync[2175]: 0 May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 27 22:43:56 streamer volumio[1750]: info: Adding plugin bluetooth to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin multiroom to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin metavolumio to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin cd_controller to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin qobuzconnect to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin smart_inputs to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: Adding plugin tidalconnect to MyMusic Plugins May 27 22:43:56 streamer volumio[1750]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 27 22:43:57 streamer shairport-sync[2174]: 0 0 0 0 0 0 0 0 --:--:-- 0:00:07 --:--:-- 0 May 27 22:43:57 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:58 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:58 streamer shairport-sync[2175]: [79B blob data] May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 27 22:43:58 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 22:43:58 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 22:43:58 streamer volumio[1750]: info: Starting MyVolumio Remote Streaming Endpoints May 27 22:43:58 streamer volumio[1750]: info: MyVolumio login type: Token May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 27 22:43:58 streamer volumio[1750]: info: Streaming services startup May 27 22:43:58 streamer volumio[1750]: info: Starting Streaming Daemon May 27 22:43:58 streamer sudo[2220]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 27 22:43:58 streamer sudo[2220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:43:58 streamer sudo[2220]: pam_unix(sudo:session): session closed for user root May 27 22:43:58 streamer volumio[1750]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:43:58 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:43:59 streamer shairport-sync[2174]: [79B blob data] May 27 22:43:59 streamer shairport-sync[2175]: [79B blob data] May 27 22:44:00 streamer shairport-sync[2174]: [79B blob data] May 27 22:44:00 streamer shairport-sync[2175]: [79B blob data] May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Volumio is not playing May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - Clipped samples monitor stopped May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2230]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2230]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2233]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2237]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 27 22:44:00 streamer sudo[2240]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2240]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2243]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2243]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2248]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2248]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer sudo[2257]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2257]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2261]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2261]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2266]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2269]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2269]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2277]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2277]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2280]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2280]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2283]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2283]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2288]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2288]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer sudo[2291]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2291]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: PeppyMeterBasic ---peppymeterbasic status stop May 27 22:44:00 streamer sudo[2294]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2294]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer sudo[2297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service May 27 22:44:00 streamer sudo[2297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:44:00 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:44:00 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:44:00 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:44:00 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::servicePushState May 27 22:44:00 streamer volumio[1750]: info: CoreStateMachine::pushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::volumioPushState May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 22:44:00 streamer volumio[1750]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 22:44:00 streamer volumio[1750]: info: camilladsp stopping service pid 2300... May 27 22:44:00 streamer sudo[2227]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer volumio[1750]: info: camilladsp service terminated, instance 1 May 27 22:44:00 streamer volumio[1750]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 22:44:00 streamer volumio[1750]: info: camilladsp service started and running in background, instance 1 May 27 22:44:00 streamer volumio[1750]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 22:44:00 streamer sudo[2233]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer volumio[1750]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. May 27 22:44:00 streamer volumio[1750]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues May 27 22:44:00 streamer volumio[1750]: at new NodeError (node:internal/errors:405:5) May 27 22:44:00 streamer volumio[1750]: at assert (node:internal/assert:14:11) May 27 22:44:00 streamer volumio[1750]: at internalConnectMultiple (node:net:1118:3) May 27 22:44:00 streamer volumio[1750]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) May 27 22:44:00 streamer volumio[1750]: at listOnTimeout (node:internal/timers:575:11) May 27 22:44:00 streamer volumio[1750]: at process.processTimers (node:internal/timers:514:7) { May 27 22:44:00 streamer volumio[1750]: code: 'ERR_INTERNAL_ASSERTION' May 27 22:44:00 streamer volumio[1750]: } May 27 22:44:00 streamer volumio[1750]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 22:44:00 streamer sudo[2248]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2274]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2240]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2243]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2230]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2237]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2257]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2261]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2269]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2266]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2286]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2280]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2291]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2277]: pam_unix(sudo:session): session closed for user root May 27 22:44:00 streamer sudo[2283]: pam_unix(sudo:session): session closed for user root May 27 22:44:01 streamer sudo[2294]: pam_unix(sudo:session): session closed for user root May 27 22:44:01 streamer sudo[2297]: pam_unix(sudo:session): session closed for user root May 27 22:44:01 streamer sudo[2288]: pam_unix(sudo:session): session closed for user root May 27 22:44:01 streamer sudo[2330]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 22:43' May 27 22:44:01 streamer sudo[2330]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"