-- Logs begin at Wed 2024-10-02 10:32:27 UTC, end at Sat 2024-10-05 18:12:18 UTC. -- Oct 05 18:11:00 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:00 volumio volumio[816]: 2+0 records in Oct 05 18:11:00 volumio volumio[816]: 2+0 records out Oct 05 18:11:00 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000608434 s, 108 MB/s Oct 05 18:11:00 volumio volumio[816]: info: camilladsp spawned new process with pid 10035, instance 1, run: true Oct 05 18:11:01 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:01 volumio volumio[816]: 1+1 records in Oct 05 18:11:01 volumio volumio[816]: 1+1 records out Oct 05 18:11:01 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.000638954 s, 102 MB/s Oct 05 18:11:01 volumio volumio[816]: info: camilladsp spawned new process with pid 10040, instance 1, run: true Oct 05 18:11:02 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:02 volumio volumio[816]: 2+0 records in Oct 05 18:11:02 volumio volumio[816]: 2+0 records out Oct 05 18:11:02 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000604214 s, 108 MB/s Oct 05 18:11:02 volumio volumio[816]: info: camilladsp spawned new process with pid 10045, instance 1, run: true Oct 05 18:11:03 volumio volumio[816]: info: VolumeController::SetAlsaVolume54 Oct 05 18:11:03 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:03 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:03 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:03 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:03 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:03 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 54 Oct 05 18:11:03 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 22 Oct 05 18:11:03 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 54 Oct 05 18:11:03 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:03 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 54 Oct 05 18:11:03 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:03 volumio volumio[816]: 2+0 records in Oct 05 18:11:03 volumio volumio[816]: 2+0 records out Oct 05 18:11:03 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000626142 s, 105 MB/s Oct 05 18:11:03 volumio volumio[816]: info: camilladsp spawned new process with pid 10051, instance 1, run: true Oct 05 18:11:04 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:04 volumio volumio[816]: 1+1 records in Oct 05 18:11:04 volumio volumio[816]: 1+1 records out Oct 05 18:11:04 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.000337863 s, 192 MB/s Oct 05 18:11:04 volumio volumio[816]: info: camilladsp spawned new process with pid 10056, instance 1, run: true Oct 05 18:11:04 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 54 Oct 05 18:11:04 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:04 volumio go-librespot[1161]: time="2024-10-05T18:11:04Z" level=debug msg="update volume to 35388/65535" Oct 05 18:11:04 volumio go-librespot[1161]: time="2024-10-05T18:11:04Z" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 18:11:04 volumio go-librespot[1161]: time="2024-10-05T18:11:04Z" level=trace msg="emitting websocket event: volume" Oct 05 18:11:04 volumio volumio[816]: SPOTIFY: received: {"type":"volume","data":{"value":54,"max":100}} Oct 05 18:11:04 volumio volumio[816]: SPOTIFY: RECEIVED SPOTIFY VOLUME 54 Oct 05 18:11:05 volumio volumio[816]: info: VolumeController::SetAlsaVolume20 Oct 05 18:11:05 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:05 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:05 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:05 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:05 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:05 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:05 volumio volumio[816]: 2+0 records in Oct 05 18:11:05 volumio volumio[816]: 2+0 records out Oct 05 18:11:05 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000624684 s, 105 MB/s Oct 05 18:11:05 volumio volumio[816]: info: camilladsp spawned new process with pid 10100, instance 1, run: true Oct 05 18:11:05 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 18:11:05 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 54 Oct 05 18:11:05 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 20 Oct 05 18:11:05 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:05 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 20 Oct 05 18:11:06 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:06 volumio volumio[816]: 2+0 records in Oct 05 18:11:06 volumio volumio[816]: 2+0 records out Oct 05 18:11:06 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000613486 s, 107 MB/s Oct 05 18:11:06 volumio volumio[816]: info: camilladsp spawned new process with pid 10105, instance 1, run: true Oct 05 18:11:07 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Oct 05 18:11:07 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:07 volumio go-librespot[1161]: time="2024-10-05T18:11:07Z" level=debug msg="update volume to 13107/65535" Oct 05 18:11:07 volumio go-librespot[1161]: time="2024-10-05T18:11:07Z" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 18:11:07 volumio go-librespot[1161]: time="2024-10-05T18:11:07Z" level=trace msg="emitting websocket event: volume" Oct 05 18:11:07 volumio volumio[816]: SPOTIFY: received: {"type":"volume","data":{"value":20,"max":100}} Oct 05 18:11:07 volumio volumio[816]: SPOTIFY: RECEIVED SPOTIFY VOLUME 20 Oct 05 18:11:07 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:07 volumio volumio[816]: 1+1 records in Oct 05 18:11:07 volumio volumio[816]: 1+1 records out Oct 05 18:11:07 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.00065312 s, 99.6 MB/s Oct 05 18:11:07 volumio volumio[816]: info: camilladsp spawned new process with pid 10110, instance 1, run: true Oct 05 18:11:08 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:08 volumio volumio[816]: 2+0 records in Oct 05 18:11:08 volumio volumio[816]: 2+0 records out Oct 05 18:11:08 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000610933 s, 107 MB/s Oct 05 18:11:08 volumio volumio[816]: info: camilladsp spawned new process with pid 10115, instance 1, run: true Oct 05 18:11:10 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:10 volumio volumio[816]: 2+0 records in Oct 05 18:11:10 volumio volumio[816]: 2+0 records out Oct 05 18:11:10 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000612184 s, 107 MB/s Oct 05 18:11:10 volumio volumio[816]: info: camilladsp spawned new process with pid 10120, instance 1, run: true Oct 05 18:11:11 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:11 volumio volumio[816]: 1+1 records in Oct 05 18:11:11 volumio volumio[816]: 1+1 records out Oct 05 18:11:11 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.000637131 s, 102 MB/s Oct 05 18:11:11 volumio volumio[816]: info: camilladsp spawned new process with pid 10125, instance 1, run: true Oct 05 18:11:12 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:12 volumio volumio[816]: 2+0 records in Oct 05 18:11:12 volumio volumio[816]: 2+0 records out Oct 05 18:11:12 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000322758 s, 203 MB/s Oct 05 18:11:12 volumio volumio[816]: info: camilladsp spawned new process with pid 10130, instance 1, run: true Oct 05 18:11:13 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:13 volumio volumio[816]: 2+0 records in Oct 05 18:11:13 volumio volumio[816]: 2+0 records out Oct 05 18:11:13 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000308487 s, 212 MB/s Oct 05 18:11:13 volumio volumio[816]: info: camilladsp spawned new process with pid 10135, instance 1, run: true Oct 05 18:11:14 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:14 volumio volumio[816]: 1+1 records in Oct 05 18:11:14 volumio volumio[816]: 1+1 records out Oct 05 18:11:14 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.000336873 s, 193 MB/s Oct 05 18:11:14 volumio volumio[816]: info: camilladsp spawned new process with pid 10140, instance 1, run: true Oct 05 18:11:15 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:15 volumio volumio[816]: 2+0 records in Oct 05 18:11:15 volumio volumio[816]: 2+0 records out Oct 05 18:11:15 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000641506 s, 102 MB/s Oct 05 18:11:15 volumio volumio[816]: info: camilladsp spawned new process with pid 10146, instance 1, run: true Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="handling transfer player command from 49dc88a9c6c0ae011b0d6852f6acbff12663c033" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="resolved context of track" uri="spotify:artist:6GbCJZrI318Ybm8mY36Of5" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=trace msg="fetched new page 0 with 5 items (list: 5)" uri="spotify:artist:6GbCJZrI318Ybm8mY36Of5" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=trace msg="fetched new page 1 with 45 items (list: 50)" uri="spotify:artist:6GbCJZrI318Ybm8mY36Of5" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="loading track (paused: true, position: 19367ms)" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:16 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:16 volumio volumio[816]: 2+0 records in Oct 05 18:11:16 volumio volumio[816]: 2+0 records out Oct 05 18:11:16 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000659058 s, 99.4 MB/s Oct 05 18:11:16 volumio volumio[816]: info: camilladsp spawned new process with pid 10151, instance 1, run: true Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=trace msg="emitting websocket event: will_play" Oct 05 18:11:16 volumio volumio[816]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","play_origin":"com.spotify.service.freetierartist"}} Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="selected format OGG_VORBIS_320 (6247905f273948cad02d78bf6446a8de4598eb58)" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="requested aes key for file 6247905f273948cad02d78bf6446a8de4598eb58, gid: 20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1367" Oct 05 18:11:16 volumio go-librespot[1161]: time="2024-10-05T18:11:16Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1147" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="fetched first chunk of 25, total size is 12991856 bytes" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=trace msg="seek to 19367ms (diff: 163ms, samples: 854084, bytes: 770129)" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="fetched chunk 4/24, size: 524288" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:17 volumio volumio[816]: 1+1 records in Oct 05 18:11:17 volumio volumio[816]: 1+1 records out Oct 05 18:11:17 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.00116156 s, 56.0 MB/s Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="created new output device" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=info msg="loaded track \"Falling to Pieces\" (paused: true, position: 19367ms, duration: 313573ms, prefetched: false)" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:17 volumio volumio[816]: info: camilladsp spawned new process with pid 10157, instance 1, run: true Oct 05 18:11:17 volumio volumio[816]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 05 18:11:17 volumio volumio[816]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 05 18:11:17 volumio volumio[816]: info: camilladsp stopping service pid 10157... Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=trace msg="emitting websocket event: metadata" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=trace msg="emitting websocket event: active" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="sending successful reply for dealer request" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 05 18:11:17 volumio volumio[816]: info: camilladsp service terminated, instance 1 Oct 05 18:11:17 volumio volumio[816]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 05 18:11:17 volumio volumio[816]: info: camilladsp service started and running in background, instance 1 Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","name":"Falling to Pieces","artist_names":["Faith No More"],"album_name":"The Real Thing","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02e081ef6de656ee714788ea0d","position":19367,"duration":313573,"release_date":"year:1989","track_number":3,"disc_number":1}} Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: received: {"type":"active","data":null} Oct 05 18:11:17 volumio volumio[816]: info: Aligning Spotify Volume to Volumio Volume Oct 05 18:11:17 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:17 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 20 Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 18:11:17 volumio go-librespot[1161]: time="2024-10-05T18:11:17Z" level=trace msg="emitting websocket event: paused" Oct 05 18:11:17 volumio volumio[816]: info: camilladsp spawned new process with pid 10161, instance 1, run: true Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","play_origin":"com.spotify.service.freetierartist"}} Oct 05 18:11:17 volumio volumio[816]: info: Spotify is playing in volatile mode Oct 05 18:11:17 volumio volumio[816]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: UNSET VOLATILE Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: {"status":"play","position":0,"title":"FM4 Davidecks | fm4.orf.at","artist":"","album":null,"albumart":"http://cdn-profiles.tunein.com/s8235/images/logoq.png?t=119","uri":"https://orf-live.ors-shoutcast.at/fm4-q2a","trackType":"webradio","seek":15672,"duration":0,"samplerate":"","bitdepth":"","channels":2,"bitrate":"192 Kbps","repeat":true,"repeatSingle":false,"consume":true,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":true,"updatedb":false,"volatile":false,"service":"webradio"} Oct 05 18:11:17 volumio volumio[816]: info: Setting Spotify stop after unset volatile call Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: PUSH STATE SPOTIFY Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: {"status":"pause","service":"spop","title":"Falling to Pieces","artist":"Faith No More","album":"The Real Thing","albumart":"https://i.scdn.co/image/ab67616d00001e02e081ef6de656ee714788ea0d","uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","trackType":"spotify","seek":19367,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 05 18:11:17 volumio volumio[816]: info: CoreCommandRouter::servicePushState Oct 05 18:11:17 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:17 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:17 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:17 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:17 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:17 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 18:11:18 volumio volumio[816]: info: Spotify Stop Oct 05 18:11:18 volumio volumio[816]: SPOTIFY: SPOTIFY STOP Oct 05 18:11:18 volumio volumio[816]: SPOTIFY: {"status":"pause","title":"Falling to Pieces","artist":"Faith No More","album":"The Real Thing","albumart":"https://i.scdn.co/image/ab67616d00001e02e081ef6de656ee714788ea0d","uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","trackType":"spotify","codec":"ogg","seek":19367,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":20,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Oct 05 18:11:18 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:18 volumio volumio[816]: 2+0 records in Oct 05 18:11:18 volumio volumio[816]: 2+0 records out Oct 05 18:11:18 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000315623 s, 208 MB/s Oct 05 18:11:18 volumio volumio[816]: info: camilladsp spawned new process with pid 10166, instance 1, run: true Oct 05 18:11:19 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 20 Oct 05 18:11:19 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:19 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:19 volumio volumio[816]: 2+0 records in Oct 05 18:11:19 volumio volumio[816]: 2+0 records out Oct 05 18:11:19 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000315206 s, 208 MB/s Oct 05 18:11:19 volumio volumio[816]: info: camilladsp spawned new process with pid 10171, instance 1, run: true Oct 05 18:11:21 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:21 volumio volumio[816]: 1+1 records in Oct 05 18:11:21 volumio volumio[816]: 1+1 records out Oct 05 18:11:21 volumio volumio[816]: 65024 bytes (65 kB, 64 KiB) copied, 0.000634839 s, 102 MB/s Oct 05 18:11:21 volumio volumio[816]: info: camilladsp spawned new process with pid 10176, instance 1, run: true Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=debug msg="handling resume player command from 49dc88a9c6c0ae011b0d6852f6acbff12663c033" Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=trace msg="seek to 19367ms (diff: 163ms, samples: 854084, bytes: 770129)" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:21 volumio volumio[816]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 05 18:11:21 volumio volumio[816]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=debug msg="resume track at 19200ms" Oct 05 18:11:21 volumio volumio[816]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 05 18:11:21 volumio volumio[816]: error: FusionDsp - WebSocket error: [object Object] Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=trace msg="scheduling prefetch in 264s" Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=debug msg="sending successful reply for dealer request" Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 05 18:11:21 volumio go-librespot[1161]: time="2024-10-05T18:11:21Z" level=trace msg="emitting websocket event: playing" Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","play_origin":"com.spotify.service.freetierartist"}} Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: PUSH STATE SPOTIFY Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: {"status":"play","service":"spop","title":"Falling to Pieces","artist":"Faith No More","album":"The Real Thing","albumart":"https://i.scdn.co/image/ab67616d00001e02e081ef6de656ee714788ea0d","uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","trackType":"spotify","seek":19367,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 05 18:11:21 volumio volumio[816]: info: CoreCommandRouter::servicePushState Oct 05 18:11:21 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:21 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:21 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:21 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: PUSH STATE SPOTIFY Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: {"status":"play","service":"spop","title":"Falling to Pieces","artist":"Faith No More","album":"The Real Thing","albumart":"https://i.scdn.co/image/ab67616d00001e02e081ef6de656ee714788ea0d","uri":"spotify:track:20nb0Wl1yqoEERbUSILuG1","trackType":"spotify","seek":19367,"duration":313,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Oct 05 18:11:21 volumio volumio[816]: info: CoreCommandRouter::servicePushState Oct 05 18:11:21 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:21 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:21 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:21 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:21 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Oct 05 18:11:22 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:22 volumio volumio[816]: 2+1 records in Oct 05 18:11:22 volumio volumio[816]: 2+1 records out Oct 05 18:11:22 volumio volumio[816]: 69632 bytes (70 kB, 68 KiB) copied, 0.000860254 s, 80.9 MB/s Oct 05 18:11:22 volumio volumio[816]: info: camilladsp spawned new process with pid 10182, instance 1, run: true Oct 05 18:11:23 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:23 volumio volumio[816]: 2+0 records in Oct 05 18:11:23 volumio volumio[816]: 2+0 records out Oct 05 18:11:23 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000623017 s, 105 MB/s Oct 05 18:11:23 volumio volumio[816]: info: camilladsp spawned new process with pid 10187, instance 1, run: true Oct 05 18:11:24 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:24 volumio volumio[816]: 2+0 records in Oct 05 18:11:24 volumio volumio[816]: 2+0 records out Oct 05 18:11:24 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000333435 s, 197 MB/s Oct 05 18:11:24 volumio volumio[816]: info: camilladsp spawned new process with pid 10192, instance 1, run: true Oct 05 18:11:25 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:25 volumio volumio[816]: 1+1 records in Oct 05 18:11:25 volumio volumio[816]: 1+1 records out Oct 05 18:11:25 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000340987 s, 181 MB/s Oct 05 18:11:25 volumio volumio[816]: info: camilladsp spawned new process with pid 10197, instance 1, run: true Oct 05 18:11:26 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:26 volumio volumio[816]: 2+0 records in Oct 05 18:11:26 volumio volumio[816]: 2+0 records out Oct 05 18:11:26 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000625152 s, 105 MB/s Oct 05 18:11:26 volumio volumio[816]: info: camilladsp spawned new process with pid 10202, instance 1, run: true Oct 05 18:11:27 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:27 volumio volumio[816]: 2+0 records in Oct 05 18:11:27 volumio volumio[816]: 2+0 records out Oct 05 18:11:27 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000616402 s, 106 MB/s Oct 05 18:11:27 volumio volumio[816]: info: camilladsp spawned new process with pid 10207, instance 1, run: true Oct 05 18:11:28 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:28 volumio volumio[816]: 1+2 records in Oct 05 18:11:28 volumio volumio[816]: 1+2 records out Oct 05 18:11:28 volumio volumio[816]: 65808 bytes (66 kB, 64 KiB) copied, 0.000359321 s, 183 MB/s Oct 05 18:11:28 volumio volumio[816]: info: camilladsp spawned new process with pid 10212, instance 1, run: true Oct 05 18:11:29 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:29 volumio volumio[816]: 2+0 records in Oct 05 18:11:29 volumio volumio[816]: 2+0 records out Oct 05 18:11:29 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000324998 s, 202 MB/s Oct 05 18:11:29 volumio volumio[816]: info: camilladsp spawned new process with pid 10217, instance 1, run: true Oct 05 18:11:30 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:30 volumio volumio[816]: 2+0 records in Oct 05 18:11:30 volumio volumio[816]: 2+0 records out Oct 05 18:11:30 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000329425 s, 199 MB/s Oct 05 18:11:30 volumio kernel: hwmon hwmon1: Voltage normalised Oct 05 18:11:30 volumio volumio[816]: info: camilladsp spawned new process with pid 10223, instance 1, run: true Oct 05 18:11:31 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:31 volumio volumio[816]: 1+1 records in Oct 05 18:11:31 volumio volumio[816]: 1+1 records out Oct 05 18:11:31 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000638068 s, 96.7 MB/s Oct 05 18:11:31 volumio volumio[816]: info: camilladsp spawned new process with pid 10228, instance 1, run: true Oct 05 18:11:32 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 05 18:11:32 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:32 volumio volumio[816]: 2+0 records in Oct 05 18:11:32 volumio volumio[816]: 2+0 records out Oct 05 18:11:32 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000703016 s, 93.2 MB/s Oct 05 18:11:32 volumio volumio[816]: info: camilladsp spawned new process with pid 10233, instance 1, run: true Oct 05 18:11:33 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 18:11:33 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 18:11:33 volumio volumio[816]: info: Discovery: Getting this device information Oct 05 18:11:33 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:33 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 18:11:34 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:34 volumio volumio[816]: 1+1 records in Oct 05 18:11:34 volumio volumio[816]: 1+1 records out Oct 05 18:11:34 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000596663 s, 103 MB/s Oct 05 18:11:34 volumio volumio[816]: info: camilladsp spawned new process with pid 10238, instance 1, run: true Oct 05 18:11:34 volumio volumio[816]: verbose: New Socket.io Connection to 192.168.178.44 from 192.168.178.53 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 16_5_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::volumioGetVisibleSources Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 05 18:11:34 volumio volumio[816]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 05 18:11:34 volumio volumio[816]: info: Received Get System Info Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 18:11:34 volumio volumio[816]: info: Discovery: Getting this device information Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:34 volumio volumio[816]: info: Listing playlists Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:34 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:36 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:36 volumio volumio[816]: 2+0 records in Oct 05 18:11:36 volumio volumio[816]: 2+0 records out Oct 05 18:11:36 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000647704 s, 101 MB/s Oct 05 18:11:36 volumio volumio[816]: info: camilladsp spawned new process with pid 10244, instance 1, run: true Oct 05 18:11:37 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:37 volumio volumio[816]: 2+0 records in Oct 05 18:11:37 volumio volumio[816]: 2+0 records out Oct 05 18:11:37 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000631141 s, 104 MB/s Oct 05 18:11:37 volumio volumio[816]: info: camilladsp spawned new process with pid 10249, instance 1, run: true Oct 05 18:11:38 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:38 volumio volumio[816]: 1+1 records in Oct 05 18:11:38 volumio volumio[816]: 1+1 records out Oct 05 18:11:38 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000313592 s, 197 MB/s Oct 05 18:11:38 volumio volumio[816]: info: camilladsp spawned new process with pid 10254, instance 1, run: true Oct 05 18:11:39 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:39 volumio volumio[816]: 2+0 records in Oct 05 18:11:39 volumio volumio[816]: 2+0 records out Oct 05 18:11:39 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000610829 s, 107 MB/s Oct 05 18:11:39 volumio volumio[816]: info: camilladsp spawned new process with pid 10259, instance 1, run: true Oct 05 18:11:39 volumio volumio[816]: info: VolumeController::SetAlsaVolume52 Oct 05 18:11:39 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:39 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:39 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:39 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:39 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:40 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 52 Oct 05 18:11:40 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 20 Oct 05 18:11:40 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 52 Oct 05 18:11:40 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:40 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 52 Oct 05 18:11:41 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:41 volumio volumio[816]: 2+0 records in Oct 05 18:11:41 volumio volumio[816]: 2+0 records out Oct 05 18:11:41 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000615152 s, 107 MB/s Oct 05 18:11:41 volumio volumio[816]: info: camilladsp spawned new process with pid 10265, instance 1, run: true Oct 05 18:11:41 volumio volumio[816]: info: VolumeController::SetAlsaVolume73 Oct 05 18:11:41 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:41 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:41 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:41 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:41 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:41 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 05 18:11:41 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 52 Oct 05 18:11:41 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 73 Oct 05 18:11:41 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:41 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 73 Oct 05 18:11:42 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:42 volumio volumio[816]: 1+1 records in Oct 05 18:11:42 volumio volumio[816]: 1+1 records out Oct 05 18:11:42 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000323331 s, 191 MB/s Oct 05 18:11:42 volumio volumio[816]: info: camilladsp spawned new process with pid 10271, instance 1, run: true Oct 05 18:11:42 volumio volumio[816]: info: VolumeController::SetAlsaVolume23 Oct 05 18:11:42 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:42 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:42 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:42 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:42 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:42 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 73 Oct 05 18:11:42 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:42 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Oct 05 18:11:42 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 73 Oct 05 18:11:42 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 23 Oct 05 18:11:42 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:42 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 23 Oct 05 18:11:43 volumio go-librespot[1161]: time="2024-10-05T18:11:43Z" level=debug msg="update volume to 47840/65535" Oct 05 18:11:43 volumio go-librespot[1161]: time="2024-10-05T18:11:43Z" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 18:11:43 volumio go-librespot[1161]: time="2024-10-05T18:11:43Z" level=trace msg="emitting websocket event: volume" Oct 05 18:11:43 volumio volumio[816]: SPOTIFY: received: {"type":"volume","data":{"value":73,"max":100}} Oct 05 18:11:43 volumio volumio[816]: SPOTIFY: RECEIVED SPOTIFY VOLUME 73 Oct 05 18:11:43 volumio volumio[816]: info: Setting Volumio Volume from Spotify: 73 Oct 05 18:11:43 volumio volumio[816]: info: VolumeController::SetAlsaVolume73 Oct 05 18:11:43 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:43 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:43 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:43 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:43 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:43 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:43 volumio volumio[816]: 2+0 records in Oct 05 18:11:43 volumio volumio[816]: 2+0 records out Oct 05 18:11:43 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.0010776 s, 60.8 MB/s Oct 05 18:11:43 volumio volumio[816]: info: camilladsp spawned new process with pid 10279, instance 1, run: true Oct 05 18:11:43 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 73 Oct 05 18:11:44 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:44 volumio volumio[816]: 2+0 records in Oct 05 18:11:44 volumio volumio[816]: 2+0 records out Oct 05 18:11:44 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000639996 s, 102 MB/s Oct 05 18:11:44 volumio volumio[816]: info: camilladsp spawned new process with pid 10284, instance 1, run: true Oct 05 18:11:44 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 23 Oct 05 18:11:44 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:44 volumio go-librespot[1161]: time="2024-10-05T18:11:44Z" level=debug msg="update volume to 15073/65535" Oct 05 18:11:44 volumio go-librespot[1161]: time="2024-10-05T18:11:44Z" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 18:11:44 volumio go-librespot[1161]: time="2024-10-05T18:11:44Z" level=trace msg="emitting websocket event: volume" Oct 05 18:11:44 volumio volumio[816]: SPOTIFY: received: {"type":"volume","data":{"value":23,"max":100}} Oct 05 18:11:44 volumio volumio[816]: SPOTIFY: RECEIVED SPOTIFY VOLUME 23 Oct 05 18:11:44 volumio volumio[816]: info: Setting Volumio Volume from Spotify: 23 Oct 05 18:11:44 volumio volumio[816]: info: VolumeController::SetAlsaVolume23 Oct 05 18:11:44 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:44 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:44 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:44 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:44 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:44 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 23 Oct 05 18:11:45 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:45 volumio volumio[816]: 1+1 records in Oct 05 18:11:45 volumio volumio[816]: 1+1 records out Oct 05 18:11:45 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000311039 s, 198 MB/s Oct 05 18:11:45 volumio volumio[816]: info: camilladsp spawned new process with pid 10290, instance 1, run: true Oct 05 18:11:45 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 05 18:11:45 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 05 18:11:45 volumio volumio[816]: info: Discovery: Getting this device information Oct 05 18:11:45 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:45 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 05 18:11:46 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:46 volumio volumio[816]: 2+0 records in Oct 05 18:11:46 volumio volumio[816]: 2+0 records out Oct 05 18:11:46 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000605777 s, 108 MB/s Oct 05 18:11:46 volumio volumio[816]: info: camilladsp spawned new process with pid 10295, instance 1, run: true Oct 05 18:11:47 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:47 volumio volumio[816]: 1+1 records in Oct 05 18:11:47 volumio volumio[816]: 1+1 records out Oct 05 18:11:47 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000643381 s, 95.9 MB/s Oct 05 18:11:47 volumio volumio[816]: info: camilladsp spawned new process with pid 10300, instance 1, run: true Oct 05 18:11:48 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:48 volumio volumio[816]: 2+0 records in Oct 05 18:11:48 volumio volumio[816]: 2+0 records out Oct 05 18:11:48 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.00062135 s, 105 MB/s Oct 05 18:11:48 volumio volumio[816]: info: camilladsp spawned new process with pid 10305, instance 1, run: true Oct 05 18:11:49 volumio volumio[816]: info: VolumeController::SetAlsaVolume40 Oct 05 18:11:49 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:11:49 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:11:49 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:11:49 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:11:49 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:11:49 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 05 18:11:49 volumio volumio[816]: SPOTIFY: SPOTIFY VOLUME 23 Oct 05 18:11:49 volumio volumio[816]: SPOTIFY: VOLUMIO VOLUME 40 Oct 05 18:11:49 volumio volumio[816]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 05 18:11:49 volumio volumio[816]: info: Setting Spotify Volume from Volumio: 40 Oct 05 18:11:49 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:49 volumio volumio[816]: 2+0 records in Oct 05 18:11:49 volumio volumio[816]: 2+0 records out Oct 05 18:11:49 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000659943 s, 99.3 MB/s Oct 05 18:11:49 volumio volumio[816]: info: camilladsp spawned new process with pid 10311, instance 1, run: true Oct 05 18:11:50 volumio volumio[816]: SPOTIFY: SETTING SPOTIFY VOLUME 40 Oct 05 18:11:50 volumio volumio[816]: info: Sending Spotify command with payload to local API: /player/volume Oct 05 18:11:50 volumio go-librespot[1161]: time="2024-10-05T18:11:50Z" level=debug msg="update volume to 26214/65535" Oct 05 18:11:50 volumio go-librespot[1161]: time="2024-10-05T18:11:50Z" level=debug msg="put connect state because VOLUME_CHANGED" Oct 05 18:11:50 volumio go-librespot[1161]: time="2024-10-05T18:11:50Z" level=trace msg="emitting websocket event: volume" Oct 05 18:11:50 volumio volumio[816]: SPOTIFY: received: {"type":"volume","data":{"value":40,"max":100}} Oct 05 18:11:50 volumio volumio[816]: SPOTIFY: RECEIVED SPOTIFY VOLUME 40 Oct 05 18:11:50 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:50 volumio volumio[816]: 1+1 records in Oct 05 18:11:50 volumio volumio[816]: 1+1 records out Oct 05 18:11:50 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000921244 s, 67.0 MB/s Oct 05 18:11:51 volumio volumio[816]: info: camilladsp spawned new process with pid 10316, instance 1, run: true Oct 05 18:11:52 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:52 volumio volumio[816]: 2+0 records in Oct 05 18:11:52 volumio volumio[816]: 2+0 records out Oct 05 18:11:52 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000603641 s, 109 MB/s Oct 05 18:11:52 volumio volumio[816]: info: camilladsp spawned new process with pid 10321, instance 1, run: true Oct 05 18:11:53 volumio volumio[816]: info: Tunnel connection is inactive, restarting it Oct 05 18:11:53 volumio volumio[816]: info: Starting Tunnel 1 Oct 05 18:11:53 volumio volumio[816]: info: Starting Tunnel Connection Checker Oct 05 18:11:53 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:53 volumio volumio[816]: 2+0 records in Oct 05 18:11:53 volumio volumio[816]: 2+0 records out Oct 05 18:11:53 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000318539 s, 206 MB/s Oct 05 18:11:53 volumio volumio[816]: info: camilladsp spawned new process with pid 10330, instance 1, run: true Oct 05 18:11:53 volumio sudo[10334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 05 18:11:53 volumio sudo[10334]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:11:53 volumio autossh[9026]: received signal to exit (15) Oct 05 18:11:53 volumio systemd[1]: Stopping MyVolumio SSH Tunnel... Oct 05 18:11:53 volumio systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Oct 05 18:11:53 volumio systemd[1]: sshtunnel.service: Succeeded. Oct 05 18:11:53 volumio systemd[1]: Stopped MyVolumio SSH Tunnel. Oct 05 18:11:53 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:11:53 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:11:53 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 05 18:11:53 volumio sudo[10334]: pam_unix(sudo:session): session closed for user root Oct 05 18:11:53 volumio volumio[816]: info: Remote SSH Started Oct 05 18:11:53 volumio autossh[10338]: port set to 0, monitoring disabled Oct 05 18:11:53 volumio autossh[10338]: starting ssh (count 1) Oct 05 18:11:53 volumio autossh[10338]: ssh child pid is 10341 Oct 05 18:11:53 volumio volumio[816]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Oct 05 18:11:53 volumio volumio[816]: info: CoreCommandRouter::volumioGetState Oct 05 18:11:54 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:54 volumio volumio[816]: 1+1 records in Oct 05 18:11:54 volumio volumio[816]: 1+1 records out Oct 05 18:11:54 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000650673 s, 94.8 MB/s Oct 05 18:11:54 volumio volumio[816]: info: camilladsp spawned new process with pid 10345, instance 1, run: true Oct 05 18:11:55 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:55 volumio volumio[816]: 2+0 records in Oct 05 18:11:55 volumio volumio[816]: 2+0 records out Oct 05 18:11:55 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.000606402 s, 108 MB/s Oct 05 18:11:55 volumio volumio[816]: info: camilladsp spawned new process with pid 10350, instance 1, run: true Oct 05 18:11:56 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 05 18:11:56 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 05 18:11:56 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:56 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:56 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:11:57 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:57 volumio volumio[816]: 2+0 records in Oct 05 18:11:57 volumio volumio[816]: 2+0 records out Oct 05 18:11:57 volumio volumio[816]: 65536 bytes (66 kB, 64 KiB) copied, 0.0006476 s, 101 MB/s Oct 05 18:11:57 volumio volumio[816]: info: camilladsp spawned new process with pid 10355, instance 1, run: true Oct 05 18:11:59 volumio volumio[816]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Oct 05 18:11:59 volumio volumio[816]: 1+1 records in Oct 05 18:11:59 volumio volumio[816]: 1+1 records out Oct 05 18:11:59 volumio volumio[816]: 61712 bytes (62 kB, 60 KiB) copied, 0.000637287 s, 96.8 MB/s Oct 05 18:11:59 volumio volumio[816]: info: camilladsp spawned new process with pid 10360, instance 1, run: true Oct 05 18:11:59 volumio volumio[816]: info: FusionDsp - Stopping FusionDsp service Oct 05 18:11:59 volumio volumio[816]: info: camilladsp service terminated, instance 1 Oct 05 18:11:59 volumio volumio[816]: info: Disabling plugin fusiondsp Oct 05 18:11:59 volumio volumio[816]: info: Preparing to generate the ALSA configuration file Oct 05 18:11:59 volumio go-librespot[1161]: time="2024-10-05T18:11:59Z" level=debug msg="fetched chunk 5/24, size: 524288" uri="spotify:track:20nb0Wl1yqoEERbUSILuG1" Oct 05 18:11:59 volumio sudo[10364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Oct 05 18:11:59 volumio sudo[10364]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:11:59 volumio systemd[1]: Stopping FusionDsp Daemon... Oct 05 18:11:59 volumio volumio[1214]: Backend configuration: Oct 05 18:11:59 volumio volumio[1214]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 05 18:11:59 volumio volumio[1214]: active_config_txt: null Oct 05 18:11:59 volumio volumio[1214]: camilla_host: 127.0.0.1 Oct 05 18:11:59 volumio volumio[1214]: camilla_port: 9876 Oct 05 18:11:59 volumio volumio[1214]: coeff_dir: /data/INTERNAL/FusionDsp/filters Oct 05 18:11:59 volumio volumio[1214]: config_dir: /data/configuration/audio_interface/fusiondsp Oct 05 18:11:59 volumio volumio[1214]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Oct 05 18:11:59 volumio volumio[1214]: log_file: /tmp/camilladsp.log Oct 05 18:11:59 volumio volumio[1214]: on_get_active_config: null Oct 05 18:11:59 volumio volumio[1214]: on_set_active_config: null Oct 05 18:11:59 volumio volumio[1214]: port: 5011 Oct 05 18:11:59 volumio volumio[1214]: supported_capture_types: null Oct 05 18:11:59 volumio volumio[1214]: supported_playback_types: null Oct 05 18:11:59 volumio volumio[1214]: update_config_symlink: false Oct 05 18:11:59 volumio volumio[1214]: update_config_txt: false Oct 05 18:11:59 volumio volumio[1214]: ======== Running on http://0.0.0.0:5011 ======== Oct 05 18:11:59 volumio volumio[1214]: (Press CTRL+C to quit) Oct 05 18:11:59 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:11:59 volumio volumio[816]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 18:11:59 volumio volumio[816]: info: Reading ALSA contributions from plugins. Oct 05 18:11:59 volumio volumio[816]: info: Asound.conf file written Oct 05 18:11:59 volumio sudo[10367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 05 18:11:59 volumio sudo[10367]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:11:59 volumio sudo[10367]: pam_unix(sudo:session): session closed for user root Oct 05 18:11:59 volumio systemd[1]: fusiondsp.service: Succeeded. Oct 05 18:11:59 volumio systemd[1]: Stopped FusionDsp Daemon. Oct 05 18:11:59 volumio sudo[10364]: pam_unix(sudo:session): session closed for user root Oct 05 18:11:59 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:11:59 volumio volumio[816]: info: Output device has changed, restarting MPD Oct 05 18:11:59 volumio sudo[10373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 18:11:59 volumio sudo[10373]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:11:59 volumio sudo[10373]: pam_unix(sudo:session): session closed for user root Oct 05 18:11:59 volumio volumio[816]: info: Output device has changed, restarting Shairport Sync Oct 05 18:11:59 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:11:59 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:11:59 volumio sudo[10376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 18:11:59 volumio sudo[10376]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:11:59 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 18:11:59 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:11:59 volumio volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 18:11:59 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:11:59 volumio volumio[816]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 18:11:59 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:11:59 volumio systemd[1]: mpd.service: Succeeded. Oct 05 18:11:59 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 18:12:00 volumio vtcs[1451]: [2024-10-05 18:12:00.034] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 05 18:12:00 volumio vtcs[1451]: [2024-10-05 18:12:00.036] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Oct 05 18:12:00 volumio vtcs[1451]: [2024-10-05 18:12:00.037] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 05 18:12:00 volumio systemd[1]: Starting Music Player Daemon... Oct 05 18:12:00 volumio volumio[816]: info: Done. Oct 05 18:12:00 volumio sudo[10385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:00 volumio sudo[10385]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 18:12:00 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 18:12:00 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:00 volumio systemd[1]: vtcs.service: Succeeded. Oct 05 18:12:00 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 18:12:00 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:00 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:00 volumio volumio[816]: info: MPD Permissions set Oct 05 18:12:00 volumio volumio[816]: info: FusionDsp - Reporting Fusion DSP Disabled Oct 05 18:12:00 volumio volumio[816]: info: Removing fusiondspeq DSP Signal Path Element Oct 05 18:12:00 volumio sudo[10385]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio sudo[10388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:00 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:00 volumio sudo[10388]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio sudo[10381]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 18:12:00 volumio sudo[10381]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio sudo[10388]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio sudo[10381]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio volumio[816]: info: Starting Shairport Sync Oct 05 18:12:00 volumio volumio[816]: info: Preparing to generate the ALSA configuration file Oct 05 18:12:00 volumio volumio[816]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 18:12:00 volumio volumio[816]: info: Reading ALSA contributions from plugins. Oct 05 18:12:00 volumio sudo[10399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 18:12:00 volumio sudo[10399]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio volumio[816]: info: Asound.conf file written Oct 05 18:12:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 18:12:00 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 18:12:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 18:12:00 volumio sudo[10402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 05 18:12:00 volumio sudo[10402]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio sudo[10402]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 18:12:00 volumio sudo[10399]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:00 volumio volumio[816]: info: Output device has changed, restarting MPD Oct 05 18:12:00 volumio sudo[10415]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 18:12:00 volumio sudo[10415]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio volumio[816]: info: Output device has changed, restarting Shairport Sync Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:00 volumio sudo[10415]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:00 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:12:00 volumio sudo[10418]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 18:12:00 volumio sudo[10418]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 05 18:12:00 volumio systemd[1]: mpd.service: Succeeded. Oct 05 18:12:00 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 18:12:01 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:01 volumio volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 18:12:01 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:01 volumio volumio[816]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio systemd[1]: Starting Music Player Daemon... Oct 05 18:12:01 volumio sudo[10427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:01 volumio sudo[10427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:01 volumio sudo[10430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:01 volumio sudo[10430]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:01 volumio volumio[816]: info: MPD Permissions set Oct 05 18:12:01 volumio volumio[816]: info: Shairport-Sync Started Oct 05 18:12:01 volumio sudo[10427]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio sudo[10423]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 18:12:01 volumio sudo[10423]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:01 volumio sudo[10423]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:01 volumio volumio[816]: info: Starting Shairport Sync Oct 05 18:12:01 volumio sudo[10430]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:01 volumio sudo[10441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 05 18:12:01 volumio sudo[10441]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:01 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 05 18:12:01 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 05 18:12:01 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:01 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:01 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 05 18:12:01 volumio sudo[10441]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:01 volumio volumio[816]: info: Shairport-Sync Started Oct 05 18:12:03 volumio volumio[816]: info: TidalConnect service stoped! Oct 05 18:12:03 volumio volumio[816]: info: TidalConnect service stoped! Oct 05 18:12:03 volumio volumio[816]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 18:12:03 volumio volumio[816]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 18:12:03 volumio sudo[10457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 18:12:03 volumio sudo[10457]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:03 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 05 18:12:03 volumio sudo[10457]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:04 volumio mpd[10435]: Oct 05 18:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 18:12:04 volumio systemd[1]: Started Music Player Daemon. Oct 05 18:12:04 volumio sudo[10376]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:04 volumio sudo[10418]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:04 volumio volumio[816]: error: MPD error: The expression evaluated to a falsy value: Oct 05 18:12:04 volumio volumio[816]: assert.ok(self.idling) Oct 05 18:12:04 volumio volumio[816]: error: The expression evaluated to a falsy value: Oct 05 18:12:04 volumio volumio[816]: assert.ok(self.idling) Oct 05 18:12:04 volumio volumio[816]: error: updateQueue error: null Oct 05 18:12:04 volumio volumio[816]: info: Executing endpoint tc_getconfig Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 05 18:12:04 volumio vtcs[10459]: STARTING TidalConnect services, version: 1.4.0.34 Oct 05 18:12:04 volumio volumio[816]: info: TidalConnect service stoped! Oct 05 18:12:04 volumio vtcs[10459]: STARTED TidalConnect services. Oct 05 18:12:04 volumio volumio[816]: info: Executing endpoint tc_connect Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 05 18:12:04 volumio volumio[816]: info: Connecting to TidalConnect Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::servicePushState Oct 05 18:12:04 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:12:04 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:12:04 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:12:04 volumio volumio[816]: info: CorePlayQueue::getTrack 0 Oct 05 18:12:04 volumio volumio[816]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::servicePushState Oct 05 18:12:04 volumio volumio[816]: info: CoreStateMachine::pushState Oct 05 18:12:04 volumio volumio[816]: info: CoreCommandRouter::volumioPushState Oct 05 18:12:04 volumio volumio[816]: info: MRS: Pushing multiroomSync output update for this device Oct 05 18:12:04 volumio volumio[816]: info: MRS: Pushing multiroomSync output Oct 05 18:12:04 volumio volumio[816]: info: CorePlayQueue::getTrack 0 Oct 05 18:12:04 volumio volumio[816]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Oct 05 18:12:04 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 05 18:12:04 volumio volumio[816]: SPOTIFY: RECEIVED VOLUMIO VOLUME 40 Oct 05 18:12:04 volumio volumio[816]: info: TidalConnect service stoped! Oct 05 18:12:04 volumio volumio[816]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 05 18:12:04 volumio volumio[816]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 05 18:12:04 volumio sudo[10489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 05 18:12:04 volumio sudo[10489]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:04 volumio sudo[10489]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:06 volumio volumio[816]: info: TidalConnect service started! Oct 05 18:12:06 volumio volumiossh-tunnel[10336]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Oct 05 18:12:07 volumio volumio[816]: info: TidalConnect service started! Oct 05 18:12:10 volumio kernel: hwmon hwmon1: Voltage normalised Oct 05 18:12:14 volumio volumio[816]: info: Enabling plugin fusiondsp Oct 05 18:12:14 volumio volumio[816]: info: Loading plugin "fusiondsp"... Oct 05 18:12:14 volumio volumio[816]: info: Applying required configuration parameters for plugin fusiondsp Oct 05 18:12:14 volumio volumio[816]: info: Preparing to generate the ALSA configuration file Oct 05 18:12:14 volumio volumio[816]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 18:12:14 volumio volumio[816]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 05 18:12:14 volumio volumio[816]: info: Reading ALSA contributions from plugins. Oct 05 18:12:14 volumio volumio[816]: info: Asound.conf file written Oct 05 18:12:14 volumio sudo[10532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 05 18:12:14 volumio sudo[10532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:14 volumio sudo[10532]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:14 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:14 volumio volumio[816]: info: Output device has changed, restarting MPD Oct 05 18:12:14 volumio sudo[10537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 05 18:12:14 volumio sudo[10537]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:14 volumio sudo[10537]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:14 volumio volumio[816]: info: Output device has changed, restarting Shairport Sync Oct 05 18:12:14 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:14 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:12:14 volumio sudo[10540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 05 18:12:14 volumio sudo[10540]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:14 volumio systemd[1]: Stopping Music Player Daemon... Oct 05 18:12:15 volumio systemd[1]: mpd.service: Succeeded. Oct 05 18:12:15 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:15 volumio volumio[816]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 05 18:12:15 volumio volumio[816]: xcb_connection_has_error() returned true Oct 05 18:12:15 volumio systemd[1]: Stopped Music Player Daemon. Oct 05 18:12:15 volumio volumio[816]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio vtcs[10459]: [2024-10-05 18:12:15.096] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 05 18:12:15 volumio vtcs[10459]: [2024-10-05 18:12:15.098] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 05 18:12:15 volumio systemd[1]: Starting Music Player Daemon... Oct 05 18:12:15 volumio volumio[816]: info: PLUGIN START: fusiondsp Oct 05 18:12:15 volumio volumio[816]: info: Loading i18n strings for locale de Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: FusionDsp - mixtype--------------------- Hardware Oct 05 18:12:15 volumio volumio[816]: info: Preparing to generate the ALSA configuration file Oct 05 18:12:15 volumio sudo[10549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:15 volumio sudo[10549]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:15 volumio volumio[816]: info: Done. Oct 05 18:12:15 volumio systemd[1]: Stopping Volumio Tidal Connect Service... Oct 05 18:12:15 volumio systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 05 18:12:15 volumio systemd[1]: vtcs.service: Succeeded. Oct 05 18:12:15 volumio systemd[1]: Stopped Volumio Tidal Connect Service. Oct 05 18:12:15 volumio sudo[10549]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:15 volumio volumio[816]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 05 18:12:15 volumio volumio[816]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Oct 05 18:12:15 volumio volumio[816]: info: Reading ALSA contributions from plugins. Oct 05 18:12:15 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:15 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 05 18:12:15 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:15 volumio volumio[816]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 05 18:12:15 volumio sudo[10552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 05 18:12:15 volumio sudo[10552]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:12:15 volumio sudo[10545]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 05 18:12:15 volumio sudo[10545]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 05 18:12:15 volumio sudo[10545]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 05 18:12:15 volumio volumio[816]: info: MPD Permissions set Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:15 volumio sudo[10552]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 05 18:12:15 volumio volumio[816]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 05 18:12:16 volumio kernel: hwmon hwmon1: Undervoltage detected! Oct 05 18:12:17 volumio volumio[816]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 18:12:17 volumio volumio[816]: TypeError: Cannot read property 'off' of undefined Oct 05 18:12:17 volumio volumio[816]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Oct 05 18:12:17 volumio volumio[816]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Oct 05 18:12:17 volumio volumio[816]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Oct 05 18:12:17 volumio volumio[816]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Oct 05 18:12:17 volumio volumio[816]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Oct 05 18:12:17 volumio volumio[816]: at Socket.emit (events.js:315:20) Oct 05 18:12:17 volumio volumio[816]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Oct 05 18:12:17 volumio volumio[816]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Oct 05 18:12:17 volumio volumio[816]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 05 18:12:18 volumio mpd[10556]: Oct 05 18:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 05 18:12:18 volumio systemd[1]: Started Music Player Daemon. Oct 05 18:12:18 volumio sudo[10540]: pam_unix(sudo:session): session closed for user root Oct 05 18:12:18 volumio sudo[10574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-05 18:11 Oct 05 18:12:18 volumio sudo[10574]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"