Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="handling pause player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="pause track at 64464ms" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="sending successful reply for dealer request" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=trace msg="emitting websocket event: paused" Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:list:popular-release-segments-main-roles:artist_7oPftvlwr6VrsViSDV7fJY","uri":"spotify:track:5pugSJ7chgFcXiUBgrZSYd","play_origin":"creator-page-retrieval-row"}} Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: Spotify is playing in volatile mode Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: UNSET VOLATILE Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"pause","position":0,"title":"Lionel Richie - Penny Lover","artist":"80s80s Love","album":"","albumart":"https://is1-ssl.mzstatic.com/image/thumb/Music124/v4/2c/4b/d9/2c4bd920-c9d2-b661-84b1-b691499f3ee7/source/600x600bb.jpg","uri":"http://streams.80s80s.de/love/mp3-192/volumio","trackType":"80s80s Radio","seek":199193,"duration":"220","samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":false,"consume":false,"volume":100,"dbVolume":null,"disableVolumeControl":true,"mute":false,"stream":"80s80s Radio","volatile":false,"service":"80s80s"} Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: Setting Spotify stop after unset volatile call Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: PUSH STATE SPOTIFY Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"pause","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::servicePushState Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreStateMachine::pushState Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioPushState Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.301+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.301+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" state=STATUS_PAUSED positionMs=0 volume=100 Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.301+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" id= title= Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.301+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" id= title= Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="handling resume player command from 32230a1b7d4bf656b3c9099194b3116a9c00ee01" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=trace msg="seek to 64464ms (diff: 70ms, samples: 2842862, bytes: 2645619)" uri="spotify:track:5pugSJ7chgFcXiUBgrZSYd" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="resume track at 64394ms" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=trace msg="scheduling prefetch in 191s" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="sending successful reply for dealer request" Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=trace msg="emitting websocket event: playing" Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:list:popular-release-segments-main-roles:artist_7oPftvlwr6VrsViSDV7fJY","uri":"spotify:track:5pugSJ7chgFcXiUBgrZSYd","resume":true,"play_origin":"creator-page-retrieval-row"}} Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: PUSH STATE SPOTIFY Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"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 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::servicePushState Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreStateMachine::pushState Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioPushState Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.790+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.790+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.790+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" id= title= Oct 11 20:06:50 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:50.790+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" id= title= Oct 11 20:06:50 roffe-mp3 volumio[1327]: info: Spotify Stop Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: SPOTIFY STOP Oct 11 20:06:50 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"play","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","codec":"ogg","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"} Oct 11 20:06:50 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:50+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 11 20:06:51 roffe-mp3 volumio[1327]: SPOTIFY: PUSH STATE SPOTIFY Oct 11 20:06:51 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"play","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"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 11 20:06:51 roffe-mp3 volumio[1327]: info: CoreCommandRouter::servicePushState Oct 11 20:06:51 roffe-mp3 volumio[1327]: info: CoreStateMachine::pushState Oct 11 20:06:51 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioPushState Oct 11 20:06:51 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:51.090+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 20:06:51 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:51.090+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" state=STATUS_PLAYING positionMs=0 volume=100 Oct 11 20:06:51 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:51.090+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" id= title= Oct 11 20:06:51 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:51.090+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" id= title= Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=info msg="playback was transferred to iPhone" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=trace msg="closed output device because of stop command" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 457" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="put connect state inactive" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=trace msg="emitting websocket event: inactive" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=trace msg="emitting websocket event: stopped" Oct 11 20:06:54 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"inactive","data":null} Oct 11 20:06:54 roffe-mp3 volumio[1327]: error: Failed to decode event: inactive Oct 11 20:06:54 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Oct 11 20:06:54 roffe-mp3 volumio[1327]: SPOTIFY: PUSH STATE SPOTIFY Oct 11 20:06:54 roffe-mp3 volumio[1327]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":3000,"duration":0,"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 11 20:06:54 roffe-mp3 volumio[1327]: info: CoreCommandRouter::servicePushState Oct 11 20:06:54 roffe-mp3 volumio[1327]: info: CoreStateMachine::pushState Oct 11 20:06:54 roffe-mp3 volumio[1327]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 20:06:54 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioPushState Oct 11 20:06:54 roffe-mp3 volumio[1327]: info: CorePlayQueue::getTrack 0 Oct 11 20:06:54 roffe-mp3 volumio[1327]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received spop Oct 11 20:06:54 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:54.670+02:00 level=INFO msg="emitting player state changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" state=STATUS_STOPPED positionMs=3000 volume=100 Oct 11 20:06:54 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:54.670+02:00 level=INFO msg="emitting player state changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" state=STATUS_STOPPED positionMs=3000 volume=100 Oct 11 20:06:54 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:54.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="192.168.86.20:58020 @ 0x24a6930" id= title= Oct 11 20:06:54 roffe-mp3 volumio5-onboarding[1572]: time=2026-10-11T20:06:54.670+02:00 level=INFO msg="emitting player metadata changed event" component=server peer="00:00:00:00:00:00%01,192.168.86.20:58020 @ 0x248a330" id= title= Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="dealer connection closed" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=info msg="loading previously persisted zeroconf credentials" username="sz*bo" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="dealer recv loop stopped" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="obtained new client token: AAE1bDqK1mCpSBOnMr3j8z7gNsDnQpoJ/Fwf/n3oUvOFDohOF446j/C/ET01hoavF9lV6Ld+4vXbW5i3CJS+f8kJfKWgTi8f9FezENYOelUmFAaUtQePWqCS9zfyx9BCHq3sq/mZdF0KA2wG7Z6NRavdZ4uZmcPDZwc00FPcSvYsrQYqsjHa0PCr9Dm9m3Z6kPn7yxwiVpDwPTqHtnptylWY/aT+2d2W4/iuepdlPlX4/tT3FhxE1OA=" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="completed keyexchange" Oct 11 20:06:54 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:54+02:00" level=debug msg="completed challenge" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=info msg="authenticated AP" username="sz*bo" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=info msg="authenticated Login5" username="sz*bo" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="restored session after logout" username="sz*bo" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="connected to gew4-dealer.spotify.com:443" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=trace msg="starting accesspoint recv loop" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=trace msg="starting dealer recv loop" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="received connection id: MDFhZWNjNDAtMjM1...RUIxMEFBRUI4MQ==" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=trace msg="emitting websocket event: playback_ready" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="update volume requested to 30146/65535" Oct 11 20:06:55 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"playback_ready","data":null} Oct 11 20:06:55 roffe-mp3 volumio[1327]: error: Failed to decode event: playback_ready Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 11 20:06:55 roffe-mp3 go-librespot[1796]: time="2026-10-11T20:06:55+02:00" level=trace msg="emitting websocket event: volume" Oct 11 20:06:55 roffe-mp3 volumio[1327]: SPOTIFY: received: {"type":"volume","data":{"max":100,"value":46}} Oct 11 20:06:55 roffe-mp3 volumio[1327]: SPOTIFY: RECEIVED SPOTIFY VOLUME 46 Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: Airplay started streaming, receiving metadatas Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: Airplay started streaming, receiving metadatas Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CorePlayQueue::getTrack 0 Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioStop Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreStateMachine::stop Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreStateMachine::stPlaybackTimer Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreStateMachine::updateTrackBlock Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CorePlayQueue::getTrackBlock Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreStateMachine::pushState Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CorePlayQueue::getTrack 0 Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 20:07:06 roffe-mp3 volumio[1327]: info: CoreCommandRouter::volumioPushState Oct 11 20:07:06 roffe-mp3 volumio[1327]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 20:07:06 roffe-mp3 shairport-sync[2667]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 20:07:06 roffe-mp3 shairport-sync[2667]: Dload Upload Total Spent Left Speed Oct 11 20:07:06 roffe-mp3 volumio[1327]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Oct 11 20:07:06 roffe-mp3 volumio[1327]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:182:10) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at /volumio/app/index.js:326:81 Oct 11 20:07:06 roffe-mp3 volumio[1327]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:534:22) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1247:12) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at CoreCommandRouter.volumioStop (/volumio/app/index.js:91:28) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:564:32) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:266:12) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at ShairportSyncReaderUDP.emit (node:events:526:35) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at Socket.emit (node:events:514:28) Oct 11 20:07:06 roffe-mp3 volumio[1327]: at UDP.onMessage [as onmessage] (node:dgram:941:8) { Oct 11 20:07:06 roffe-mp3 volumio[1327]: code: 'NumberExpected' Oct 11 20:07:06 roffe-mp3 volumio[1327]: } Oct 11 20:07:06 roffe-mp3 volumio[1327]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 20:07:06 roffe-mp3 shairport-sync[2667]: [79B blob data] Oct 11 20:07:06 roffe-mp3 shairport-sync[2665]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 11 20:07:06 roffe-mp3 shairport-sync[2665]: Dload Upload Total Spent Left Speed Oct 11 20:07:06 roffe-mp3 sudo[2687]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-11 20:06' Oct 11 20:07:06 roffe-mp3 sudo[2687]: 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"