-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2024-11-23 20:16:30 UTC. -- Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: info: Retrieving Cloud Streaming UI Nov 23 20:15:01 volumio volumio[978]: info: Getting Tidal Cloud Configuration Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: info: Getting Qobuz Cloud Configuration Nov 23 20:15:01 volumio volumio[978]: info: Asking plugin for UI Config Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: info: Getting Spotify Cloud Configuration Nov 23 20:15:01 volumio volumio[978]: info: Asking plugin for UI Config Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: info: Saving Spotify Acccount Nov 23 20:15:01 volumio volumio[978]: info: Got Tidal Cloud Configuration Nov 23 20:15:01 volumio volumio[978]: info: Got it Nov 23 20:15:01 volumio volumio[978]: info: Got it Nov 23 20:15:01 volumio volumio[978]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:01 volumio volumio[978]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::volumioGetBrowseSources Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Nov 23 20:15:01 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Nov 23 20:15:05 volumio go-librespot[1222]: time="2024-11-23T20:15:05Z" level=debug msg="fetched chunk 6/20, size: 524288" uri="spotify:track:4HCcvFdHfwR2u3WPPPVRv6" Nov 23 20:15:06 volumio volumio[978]: info: CoreCommandRouter::volumioGetQueue Nov 23 20:15:06 volumio volumio[978]: info: CoreStateMachine::getQueue Nov 23 20:15:06 volumio volumio[978]: info: CorePlayQueue::getQueue Nov 23 20:15:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:15:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:15:07 volumio volumio[978]: info: Discovery: Getting this device information Nov 23 20:15:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:15:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:15:13 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Nov 23 20:15:13 volumio volumio[978]: info: In handleBrowseUri, curUri=spotify:artist:0bzfPKdbXL5ezYW2z3UGQj Nov 23 20:15:14 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:7KX65PC1UZuImsUInThbav Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:5yGVHzJ2Se8pDzR7FIYUlU Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:0f2zcrhhHT59aIGQvRlTZ1 Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:7oOEFDLSQscl0uGulnIEmG Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:0qprlw0jfsW4H9cG0FFE0Z Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:4xyZ8TMuWWlSCN3NRUsOCq Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:4mv58j67K96UMIeYvpffHB Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:2FZAqlzZzp0xMsBKgGrSVH Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:5TWbNkeJnYkcT04J81apOr Nov 23 20:15:14 volumio volumio[978]: info: Preloading song: spotify:track:4znKp61MTtp04IvAnmtX40 Nov 23 20:15:14 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:14 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:14 volumio volumio[978]: info: No valid Plugin REST Endpoint Nov 23 20:15:16 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioReplaceandPlayItems Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::ClearQueue Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::stop Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::serviceStop Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::serviceStop Nov 23 20:15:16 volumio volumio[978]: info: Spotify Stop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: SPOTIFY STOP Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: {"status":"play","title":"Green & Gold","artist":"Lianne La Havas","album":"Blood","albumart":"https://i.scdn.co/image/ab67616d00001e027c8bb247d2b2198d009ea6cf","uri":"spotify:track:4HCcvFdHfwR2u3WPPPVRv6","trackType":"spotify","codec":"ogg","seek":11278,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":99,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Nov 23 20:15:16 volumio volumio[978]: info: Sending Spotify command to local API: /player/pause Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::clearPlayQueue Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::saveQueue Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::addQueueItems Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::addQueueItems Nov 23 20:15:16 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:7KX65PC1UZuImsUInThbav Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:7KX65PC1UZuImsUInThbav in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:7KX65PC1UZuImsUInThbav Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="pause track at 50640ms" Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","service":"spop","name":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","type":"song","duration":210,"albumart":"https://i.scdn.co/image/ab67616d0000b273d38e121683bc208a4530fb22","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::saveQueue Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPlay Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::play index 0 Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::addQueueItems Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::addQueueItems Nov 23 20:15:16 volumio volumio[978]: info: Preload queue cleared Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:5yGVHzJ2Se8pDzR7FIYUlU Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:5yGVHzJ2Se8pDzR7FIYUlU in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:5yGVHzJ2Se8pDzR7FIYUlU Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:0f2zcrhhHT59aIGQvRlTZ1 Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:0f2zcrhhHT59aIGQvRlTZ1 in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:0f2zcrhhHT59aIGQvRlTZ1 Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:7oOEFDLSQscl0uGulnIEmG Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:7oOEFDLSQscl0uGulnIEmG in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:7oOEFDLSQscl0uGulnIEmG Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:0qprlw0jfsW4H9cG0FFE0Z Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:0qprlw0jfsW4H9cG0FFE0Z in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:0qprlw0jfsW4H9cG0FFE0Z Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:4xyZ8TMuWWlSCN3NRUsOCq Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:4xyZ8TMuWWlSCN3NRUsOCq in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:4xyZ8TMuWWlSCN3NRUsOCq Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:4mv58j67K96UMIeYvpffHB Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:4mv58j67K96UMIeYvpffHB in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:4mv58j67K96UMIeYvpffHB Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:2FZAqlzZzp0xMsBKgGrSVH Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:2FZAqlzZzp0xMsBKgGrSVH in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:2FZAqlzZzp0xMsBKgGrSVH Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:5TWbNkeJnYkcT04J81apOr Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:5TWbNkeJnYkcT04J81apOr in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:5TWbNkeJnYkcT04J81apOr Nov 23 20:15:16 volumio volumio[978]: info: Adding Item to queue: spotify:track:4znKp61MTtp04IvAnmtX40 Nov 23 20:15:16 volumio volumio[978]: info: Exploding uri spotify:track:4znKp61MTtp04IvAnmtX40 in service spop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: EXPLODING URI:spotify:track:4znKp61MTtp04IvAnmtX40 Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::stop Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::serviceStop Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::serviceStop Nov 23 20:15:16 volumio volumio[978]: info: Spotify Stop Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: SPOTIFY STOP Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: {"status":"play","title":"Green & Gold","artist":"Lianne La Havas","album":"Blood","albumart":"https://i.scdn.co/image/ab67616d00001e027c8bb247d2b2198d009ea6cf","uri":"spotify:track:4HCcvFdHfwR2u3WPPPVRv6","trackType":"spotify","codec":"ogg","seek":11278,"duration":278,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":99,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Nov 23 20:15:16 volumio volumio[978]: info: Sending Spotify command to local API: /player/pause Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::play index undefined Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::startPlaybackTimer Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrack 0 Nov 23 20:15:16 volumio volumio[978]: info: [1732392916566] ControllerSpotify::clearAddPlayTrack Nov 23 20:15:16 volumio volumio[978]: info: Sending Spotify command with payload to local API: /player/play Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=trace msg="emitting websocket event: paused" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="pause track at 51144ms" Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4HCcvFdHfwR2u3WPPPVRv6","play_origin":"free-tier-artist"}} Nov 23 20:15:16 volumio volumio[978]: info: Spotify is playing in volatile mode Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: UNSET VOLATILE Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: {"status":"stop","position":0,"title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d0000b273d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","codec":"ogg","seek":0,"duration":210,"samplerate":"320 kbps","bitdepth":"16 bit","repeat":true,"repeatSingle":false,"consume":false,"volume":99,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","updatedb":false,"volatile":false,"service":"spop"} Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:16 volumio volumio[978]: 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} Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=trace msg="emitting websocket event: paused" Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:4HCcvFdHfwR2u3WPPPVRv6","play_origin":"free-tier-artist"}} Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:16 volumio volumio[978]: 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,"stream":false,"repeatMode":"all"} Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5yGVHzJ2Se8pDzR7FIYUlU","service":"spop","name":"Home Again","artist":"Michael Kiwanuka","album":"Home Again (Deluxe Version)","type":"song","duration":209,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf3cd9588ec89ab911b70780","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:2FZAqlzZzp0xMsBKgGrSVH","service":"spop","name":"Rebel Soul","artist":"Michael Kiwanuka","album":"Rebel Soul","type":"song","duration":207,"albumart":"https://i.scdn.co/image/ab67616d0000b27352e985fd740c322aa0eed304","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0f2zcrhhHT59aIGQvRlTZ1","service":"spop","name":"One More Night","artist":"Michael Kiwanuka","album":"Love & Hate","type":"song","duration":233,"albumart":"https://i.scdn.co/image/ab67616d0000b2731070095e88dce90a090171b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:0qprlw0jfsW4H9cG0FFE0Z","service":"spop","name":"Cold Little Heart","artist":"Michael Kiwanuka","album":"Love & Hate","type":"song","duration":597,"albumart":"https://i.scdn.co/image/ab67616d0000b2731070095e88dce90a090171b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="resolved context of track" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="loading track (paused: false, position: 1ms)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4mv58j67K96UMIeYvpffHB","service":"spop","name":"Floating Parade","artist":"Michael Kiwanuka","album":"Floating Parade","type":"song","duration":229,"albumart":"https://i.scdn.co/image/ab67616d0000b2735ea86904c66af53fb0e0d356","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4xyZ8TMuWWlSCN3NRUsOCq","service":"spop","name":"The Rest Of Me","artist":"Michael Kiwanuka","album":"The Rest Of Me","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b273181aae7a2d84a5b0d379f3d8","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:7oOEFDLSQscl0uGulnIEmG","service":"spop","name":"Love & Hate","artist":"Michael Kiwanuka","album":"Love & Hate","type":"song","duration":427,"albumart":"https://i.scdn.co/image/ab67616d0000b2731070095e88dce90a090171b5","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:5TWbNkeJnYkcT04J81apOr","service":"spop","name":"I'm Getting Ready","artist":"Michael Kiwanuka","album":"Home Again (Deluxe Version)","type":"song","duration":141,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf3cd9588ec89ab911b70780","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: GET TRACK: [{"uri":"spotify:track:4znKp61MTtp04IvAnmtX40","service":"spop","name":"Rest","artist":"Michael Kiwanuka","album":"Home Again (Deluxe Version)","type":"song","duration":230,"albumart":"https://i.scdn.co/image/ab67616d0000b273cf3cd9588ec89ab911b70780","samplerate":"320 kbps","bitdepth":"16 bit","bitrate":"","codec":"ogg","trackType":"spotify"}] Nov 23 20:15:16 volumio volumio[978]: info: CoreCommandRouter::volumioPushQueue Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::saveQueue Nov 23 20:15:16 volumio volumio[978]: info: CoreStateMachine::updateTrackBlock Nov 23 20:15:16 volumio volumio[978]: info: CorePlayQueue::getTrackBlock Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=trace msg="emitting websocket event: will_play" Nov 23 20:15:16 volumio volumio[978]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","play_origin":"go-librespot"}} Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="selected format OGG_VORBIS_320 (2b8e58ce45a8453be3c379e7be4f03c40e158fcd)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:16 volumio go-librespot[1222]: time="2024-11-23T20:15:16Z" level=debug msg="requested aes key for file 2b8e58ce45a8453be3c379e7be4f03c40e158fcd, gid: 7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="fetched first chunk of 16, total size is 8316944 bytes" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=trace msg="seek to 1ms (diff: 1ms, samples: 44, bytes: 0)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=info msg="loaded track \"Cold Little Heart - Radio Edit\" (paused: false, position: 1ms, duration: 210299ms, prefetched: false)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=trace msg="scheduling prefetch in 180s" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=trace msg="emitting websocket event: metadata" Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","name":"Cold Little Heart - Radio Edit","artist_names":["Michael Kiwanuka"],"album_name":"Cold Little Heart (Radio Edit)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","position":1,"duration":210299,"release_date":"year:2017 month:2 day:24","track_number":1,"disc_number":1}} Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=trace msg="emitting websocket event: playing" Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","play_origin":"go-librespot"}} Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: {"status":"play","service":"spop","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","seek":1,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 23 20:15:17 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:17 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:17 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:17 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: {"status":"play","service":"spop","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","seek":1,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 23 20:15:17 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:17 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:17 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:17 volumio volumio[978]: SPOTIFY: RECEIVED VOLUMIO VOLUME 99 Nov 23 20:15:17 volumio go-librespot[1222]: time="2024-11-23T20:15:17Z" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 20:15:22 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 20:15:30 volumio go-librespot[1222]: time="2024-11-23T20:15:30Z" level=debug msg="fetched chunk 4/15, size: 524288" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:34 volumio volumio[978]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 23 20:15:34 volumio volumio[978]: info: Preparing to save Alsa Options, stopping services first Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::volumioPause Nov 23 20:15:34 volumio volumio[978]: info: CoreStateMachine::pause Nov 23 20:15:34 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Nov 23 20:15:34 volumio volumio[978]: info: CoreStateMachine::servicePause Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::servicePause Nov 23 20:15:34 volumio volumio[978]: info: Spotify Received pause Nov 23 20:15:34 volumio volumio[978]: SPOTIFY: SPOTIFY PAUSE Nov 23 20:15:34 volumio volumio[978]: SPOTIFY: {"status":"play","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","codec":"ogg","seek":1,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":99,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"updatedb":false,"volatile":true,"service":"spop"} Nov 23 20:15:34 volumio volumio[978]: info: Sending Spotify command to local API: /player/pause Nov 23 20:15:34 volumio volumio[978]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"snd_allo_digione"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:15:34 volumio volumio[978]: info: Device 2 does not have any Mixer Control Available Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 20:15:34 volumio volumio[978]: info: Updating Volume Controller Parameters: Device: 2 Name: snd_allo_digione Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 20:15:34 volumio volumio[978]: info: Disabling external Volume Control Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:15:34 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:34 volumio volumio[978]: info: Preparing to generate the ALSA configuration file Nov 23 20:15:34 volumio go-librespot[1222]: time="2024-11-23T20:15:34Z" level=debug msg="pause track at 17683ms" Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 20:15:34 volumio go-librespot[1222]: time="2024-11-23T20:15:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 20:15:34 volumio volumio[978]: info: Asound.conf file written Nov 23 20:15:34 volumio sudo[1899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Nov 23 20:15:34 volumio go-librespot[1222]: time="2024-11-23T20:15:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:34 volumio go-librespot[1222]: time="2024-11-23T20:15:34Z" level=trace msg="emitting websocket event: paused" Nov 23 20:15:34 volumio sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio sudo[1899]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:34 volumio volumio[978]: info: Output device has changed, restarting MPD Nov 23 20:15:34 volumio sudo[1904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 20:15:34 volumio volumio[978]: info: Output device has changed, restarting Shairport Sync Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio sudo[1904]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:34 volumio sudo[1904]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:34 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 20:15:34 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio systemd[1]: Stopping Music Player Daemon... Nov 23 20:15:34 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 20:15:34 volumio volumio[978]: info: Output device has changed, restarting MPD Nov 23 20:15:34 volumio systemd[1]: mpd.service: Succeeded. Nov 23 20:15:34 volumio systemd[1]: Stopped Music Player Daemon. Nov 23 20:15:34 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 20:15:34 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio systemd[1]: Starting Music Player Daemon... Nov 23 20:15:34 volumio volumio[978]: info: Output device has changed, restarting Shairport Sync Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:34 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:34 volumio sudo[1916]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 20:15:34 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 23 20:15:34 volumio systemd[1]: mpd.service: Succeeded. Nov 23 20:15:34 volumio systemd[1]: Stopped Music Player Daemon. Nov 23 20:15:34 volumio systemd[1]: Starting Music Player Daemon... Nov 23 20:15:34 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 20:15:34 volumio volumio[978]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","play_origin":"go-librespot"}} Nov 23 20:15:34 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:34 volumio volumio[978]: SPOTIFY: {"status":"pause","service":"spop","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","seek":17001,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:34 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:34 volumio volumio[978]: info: MPD Permissions set Nov 23 20:15:34 volumio volumio[978]: info: MPD Permissions set Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio sudo[1922]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 20:15:34 volumio sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:15:34 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:15:34 volumio sudo[1922]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:34 volumio volumio[978]: info: Starting Shairport Sync Nov 23 20:15:34 volumio volumio[978]: info: Starting Shairport Sync Nov 23 20:15:34 volumio sudo[1934]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 20:15:34 volumio sudo[1934]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:35 volumio sudo[1937]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 20:15:35 volumio sudo[1937]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:15:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 23 20:15:35 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 23 20:15:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 23 20:15:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 23 20:15:35 volumio sudo[1934]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:35 volumio volumio[978]: info: Shairport-Sync Started Nov 23 20:15:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 23 20:15:35 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 23 20:15:35 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 23 20:15:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 23 20:15:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 23 20:15:35 volumio sudo[1937]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:35 volumio volumio[978]: info: Shairport-Sync Started Nov 23 20:15:35 volumio mpd[1932]: Nov 23 20:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 20:15:35 volumio systemd[1]: Started Music Player Daemon. Nov 23 20:15:35 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:35 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Nov 23 20:15:35 volumio volumio[978]: error: MPD error: The expression evaluated to a falsy value: Nov 23 20:15:35 volumio volumio[978]: assert.ok(self.idling) Nov 23 20:15:35 volumio volumio[978]: error: The expression evaluated to a falsy value: Nov 23 20:15:35 volumio volumio[978]: assert.ok(self.idling) Nov 23 20:15:35 volumio volumio[978]: error: updateQueue error: null Nov 23 20:15:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:15:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:15:37 volumio volumio[978]: info: Discovery: Getting this device information Nov 23 20:15:37 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:15:37 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:15:38 volumio volumio[978]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Nov 23 20:15:38 volumio volumio[978]: info: CoreStateMachine::getcurrentVolume Nov 23 20:15:38 volumio volumio[978]: info: CoreCommandRouter::volumioRetrievevolume Nov 23 20:15:38 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:38 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::volumioVolatilePlay Nov 23 20:15:43 volumio volumio[978]: info: CoreStateMachine::volatilePlay Nov 23 20:15:43 volumio volumio[978]: info: Spotify Play Nov 23 20:15:43 volumio volumio[978]: info: Sending Spotify command to local API: /player/resume Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=trace msg="seek to 17683ms (diff: 41ms, samples: 779820, bytes: 653264)" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5011 us, period size = 221 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 99 frames" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=debug msg="resume track at 17642ms" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=trace msg="scheduling prefetch in 163s" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Nov 23 20:15:43 volumio go-librespot[1222]: time="2024-11-23T20:15:43Z" level=trace msg="emitting websocket event: playing" Nov 23 20:15:43 volumio volumio[978]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:7KX65PC1UZuImsUInThbav","play_origin":"go-librespot"}} Nov 23 20:15:43 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:43 volumio volumio[978]: SPOTIFY: {"status":"play","service":"spop","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","seek":17001,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:43 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:43 volumio volumio[978]: SPOTIFY: PUSH STATE SPOTIFY Nov 23 20:15:43 volumio volumio[978]: SPOTIFY: {"status":"play","service":"spop","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","seek":17001,"duration":210,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::servicePushState Nov 23 20:15:43 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:15:43 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:15:53 volumio go-librespot[1222]: time="2024-11-23T20:15:53Z" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:7KX65PC1UZuImsUInThbav" Nov 23 20:16:04 volumio go-librespot[1222]: time="2024-11-23T20:16:04Z" level=error msg="output device failed" error="ALSA error at snd_pcm_recover: Input/output error" Nov 23 20:16:04 volumio kernel: usb 1-1.2: USB disconnect, device number 3 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot: pcm_plug.c:71: snd_pcm_plug_close: Assertion `plug->gen.slave == plug->req_slave' failed. Nov 23 20:16:04 volumio go-librespot[1222]: SIGABRT: abort Nov 23 20:16:04 volumio go-librespot[1222]: PC=0xb6c51fb4 m=5 sigcode=4294967290 Nov 23 20:16:04 volumio go-librespot[1222]: signal arrived during cgo execution Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 62 gp=0x19099e8 m=5 mp=0x1880008 [syscall]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.cgocall(0x5811e8, 0x1ba9ed4) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/cgocall.go:157 +0x50 fp=0x1ba9ebc sp=0x1ba9ea4 pc=0x1d56c Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output._Cfunc_snd_pcm_close(0xb5602cd0) Nov 23 20:16:04 volumio go-librespot[1222]: _cgo_gotypes.go:406 +0x38 fp=0x1ba9ed0 sp=0x1ba9ebc pc=0x4976c0 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.(*output).Close.func1(0x1a36a80) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:450 +0x50 fp=0x1ba9eec sp=0x1ba9ed0 pc=0x49c400 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.(*output).Close(0x1a36a80) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:450 +0xdc fp=0x1ba9f1c sp=0x1ba9eec pc=0x49c21c Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.(*Output).Close(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/output.go:96 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/player.(*Player).manageLoop(0x1b18680) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/player/player.go:221 +0xb2c fp=0x1ba9fe4 sp=0x1ba9f1c pc=0x4a7c58 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/player.NewPlayer.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x28 fp=0x1ba9fec sp=0x1ba9fe4 pc=0x4a7040 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1ba9fec sp=0x1ba9fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/player.NewPlayer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x1e4 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 1 gp=0x1802128 m=nil [select, 4 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1a5dd0c sp=0x1a5dcf8 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1a5de0c, 0x19bddd8, 0x0, 0x0, 0x2, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1a5ddac sp=0x1a5dd0c pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/zeroconf.(*Zeroconf).Serve(0x19ee550, 0x1a5de88) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x228 fp=0x1a5de50 sp=0x1a5ddac pc=0x528c80 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).withAppPlayer(0x189c360, 0x19e6040) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x1a5dec0 sp=0x1a5de50 pc=0x578aac Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).withCredentials(0x189c360, {0x5f3170, 0x18a2510}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x3d4 fp=0x1a5df1c sp=0x1a5dec0 pc=0x578184 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).SpotifyToken(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131 Nov 23 20:16:04 volumio go-librespot[1222]: main.main() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x530 fp=0x1a5dfa8 sp=0x1a5df1c pc=0x57a0cc Nov 23 20:16:04 volumio go-librespot[1222]: runtime.main() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x1a5dfec sp=0x1a5dfa8 pc=0x5c29c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1a5dfec sp=0x1a5dfec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle), 4 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1842fd4 sp=0x1842fc0 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goparkunlock(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.forcegchelper() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x1842fec sp=0x1842fd4 pc=0x5c668 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1842fec sp=0x1842fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.init.5 in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 3 gp=0x1802b48 m=nil [GC sweep wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x18437c4 sp=0x18437b0 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goparkunlock(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.bgsweep(0x186a000) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x18437e4 sp=0x18437c4 pc=0x4506c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcenable.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x18437ec sp=0x18437e4 pc=0x3518c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x18437ec sp=0x18437ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcenable in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 4 gp=0x1802c68 m=nil [GC scavenge wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1843fb4 sp=0x1843fa0 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goparkunlock(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.(*scavengerState).park(0xaa9020) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1843fc8 sp=0x1843fb4 pc=0x4238c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.bgscavenge(0x186a000) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1843fe4 sp=0x1843fc8 pc=0x42ac4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcenable.gowrap2() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x1843fec sp=0x1843fe4 pc=0x35138 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1843fec sp=0x1843fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcenable in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 18 gp=0x1884248 m=nil [finalizer wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x183e78c sp=0x183e778 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.runfinq() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x183e7ec sp=0x183e78c pc=0x33f78 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x183e7ec sp=0x183e7ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.createfing in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 19 gp=0x1884a28 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848f08, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x18425dc sp=0x18425c8 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848ef8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x18425f4 sp=0x18425dc pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848ef8, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1842608 sp=0x18425f4 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1898f68, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x184261c sp=0x1842608 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Accept(0x1898f50) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1842664 sp=0x184261c pc=0x10c974 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).accept(0x1898f50) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x18426cc sp=0x1842664 pc=0x2a2504 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPListener).accept(0x18ac3a8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x18426e4 sp=0x18426cc pc=0x2ba604 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPListener).Accept(0x18ac3a8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x1842700 sp=0x18426e4 pc=0x2b9670 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*onceCloseListener).Accept(0x191c300) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x34 fp=0x1842718 sp=0x1842700 pc=0x45e914 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Server).Serve(0x1920148, {0x72527c, 0x18ac3a8}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x18427b0 sp=0x1842718 pc=0x43cfd0 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.Serve(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*ApiServer).serve(0x189b9c0) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x18427e4 sp=0x18427b0 pc=0x57084c Nov 23 20:16:04 volumio go-librespot[1222]: main.NewApiServer.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x18427ec sp=0x18427e4 pc=0x56ff20 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x18427ec sp=0x18427ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by main.NewApiServer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 6 gp=0x1884b48 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848d28, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1a58e84 sp=0x1a58e70 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848d18, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1a58e9c sp=0x1a58e84 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848d18, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1a58eb0 sp=0x1a58e9c pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x19ee608, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a58ec4 sp=0x1a58eb0 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).RawRead(0x19ee5f0, 0x19d41b0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1a58ef0 sp=0x1a58ec4 pc=0x10d578 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*rawConn).Read(0x182ea18, 0x19d41b0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x1a58f10 sp=0x1a58ef0 pc=0x2b4a2c Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1acda40, 0x19d4180, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x1a58f40 sp=0x1a58f10 pc=0x4c9804 Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x19d5dd8, {0x1c4e000, 0x10000, 0x10000}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x1a58f9c sp=0x1a58f40 pc=0x4cd7ec Nov 23 20:16:04 volumio go-librespot[1222]: github.com/grandcat/zeroconf.(*Server).recv4(0x186f080, 0x19d5dd0) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x1a58fe0 sp=0x1a58f9c pc=0x52407c Nov 23 20:16:04 volumio go-librespot[1222]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x1a58fec sp=0x1a58fe0 pc=0x523d34 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1a58fec sp=0x1a58fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 29 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 23 gp=0x1884d88 m=nil [GC worker (idle)]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7f4, 0x1bb6a20, 0x1a, 0xa, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x183ef90 sp=0x183ef7c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcBgMarkWorker() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x183efec sp=0x183ef90 pc=0x37db4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x183efec sp=0x183efec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 7 gp=0x1908248 m=nil [IO wait, 4 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848c88, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x193a65c sp=0x193a648 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848c78, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x193a674 sp=0x193a65c pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848c78, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x193a688 sp=0x193a674 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x19ee658, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x193a69c sp=0x193a688 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).RawRead(0x19ee640, 0x1a74030) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x193a6c8 sp=0x193a69c pc=0x10d578 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*rawConn).Read(0x182ea40, 0x1a74030) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x193a6e8 sp=0x193a6c8 pc=0x2b4a2c Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x1acda50, 0x1a74000, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x193a718 sp=0x193a6e8 pc=0x4c9804 Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Nov 23 20:16:04 volumio go-librespot[1222]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x19d5e08, {0x1a64000, 0x10000, 0x10000}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x193a79c sp=0x193a718 pc=0x4d1174 Nov 23 20:16:04 volumio go-librespot[1222]: github.com/grandcat/zeroconf.(*Server).recv6(0x186f080, 0x19d5e00) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x193a7e0 sp=0x193a79c pc=0x524270 Nov 23 20:16:04 volumio go-librespot[1222]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x193a7ec sp=0x193a7e0 pc=0x523cd8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x193a7ec sp=0x193a7ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 29 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 36 gp=0x1908488 m=nil [GC worker (idle)]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7f4, 0x1bb6a08, 0x1a, 0xa, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x193af90 sp=0x193af7c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcBgMarkWorker() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x193afec sp=0x193af90 pc=0x37db4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x193afec sp=0x193afec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 24 gp=0x1884ea8 m=nil [GC worker (idle)]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7f4, 0x18aa6d8, 0x1a, 0xa, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x183f790 sp=0x183f77c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcBgMarkWorker() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x183f7ec sp=0x183f790 pc=0x37db4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x183f7ec sp=0x183f7ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 25 gp=0x1884fc8 m=nil [GC worker (idle)]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7f4, 0x18aa6f0, 0x1a, 0xa, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x183ff90 sp=0x183ff7c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gcBgMarkWorker() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x183ffec sp=0x183ff90 pc=0x37db4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x183ffec sp=0x183ffec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 37 gp=0x19098c8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x18406a8 sp=0x1840694 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x18407d4, 0x184076c, 0x0, 0x0, 0x2, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1840748 sp=0x18406a8 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/ap.(*Accesspoint).pongAckTicker(0x18b1cc8) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:319 +0x8c fp=0x18407e4 sp=0x1840748 pc=0x48d514 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/ap.NewAccesspoint.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x28 fp=0x18407ec sp=0x18407e4 pc=0x48b71c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x18407ec sp=0x18407ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/ap.NewAccesspoint in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x188 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 61 gp=0x1c0c6c8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1ba8e88 sp=0x1ba8e74 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1ba8fd4, 0x1ba8f50, 0x0, 0x0, 0x2, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1ba8f28 sp=0x1ba8e88 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/dealer.(*Dealer).pingTicker(0x1c7aaa8) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:107 +0x8c fp=0x1ba8fe4 sp=0x1ba8f28 pc=0x4b85d0 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/dealer.NewDealer.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x28 fp=0x1ba8fec sp=0x1ba8fe4 pc=0x4b80a0 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1ba8fec sp=0x1ba8fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/dealer.NewDealer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x180 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 60 gp=0x1c0c7e8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x18416a8 sp=0x1841694 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x18417a4, 0x184176c, 0x0, 0x0, 0x5, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1841748 sp=0x18416a8 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x199e648) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x18417e4 sp=0x1841748 pc=0x47408c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.newConn.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x18417ec sp=0x18417e4 pc=0x473c80 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x18417ec sp=0x18417ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by nhooyr.io/websocket.newConn in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 106 gp=0x1c0c908 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848968, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1a5bad4 sp=0x1a5bac0 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848958, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1a5baec sp=0x1a5bad4 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848958, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1a5bb00 sp=0x1a5baec pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1a510a8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1a5bb14 sp=0x1a5bb00 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x1a51090, {0x1bcc000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1a5bb5c sp=0x1a5bb14 pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x1a51090, {0x1bcc000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1a5bb88 sp=0x1a5bb5c pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x1b91140, {0x1bcc000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1a5bbb4 sp=0x1a5bb88 pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x1b91140, {0x1bcc000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x1a5bbd4 sp=0x1a5bbb4 pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*atLeastReader).Read(0x18a3040, {0x1bcc000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1a5bc00 sp=0x1a5bbd4 pc=0x386018 Nov 23 20:16:04 volumio go-librespot[1222]: bytes.(*Buffer).ReadFrom(0x18b580c, {0x723a98, 0x18a3040}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1a5bc3c sp=0x1a5bc00 pc=0x136d6c Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readFromUntil(0x18b5688, {0x722604, 0x1b91140}, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1a5bc64 sp=0x1a5bc3c pc=0x386274 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecordOrCCS(0x18b5688, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1a5bdc8 sp=0x1a5bc64 pc=0x383974 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecord(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).Read(0x18b5688, {0x1bd2000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1a5bdf8 sp=0x1a5bdc8 pc=0x3897e4 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).Read(0x1a75bf0, {0x1ba2724, 0x9, 0x9}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x1a5be1c sp=0x1a5bdf8 pc=0x15ab44 Nov 23 20:16:04 volumio go-librespot[1222]: io.ReadAtLeast({0x7227f8, 0x1a75bf0}, {0x1ba2724, 0x9, 0x9}, 0x9) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x1a5be48 sp=0x1a5be1c pc=0x1020b0 Nov 23 20:16:04 volumio go-librespot[1222]: io.ReadFull(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.http2readFrameHeader({0x1ba2724, 0x9, 0x9}, {0x7227f8, 0x1a75bf0}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1a5be70 sp=0x1a5be48 pc=0x3f30fc Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*http2Framer).ReadFrame(0x1ba2700) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1a5beec sp=0x1a5be70 pc=0x3f3894 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*http2clientConnReadLoop).run(0x1a5bfdc) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1a5bfa4 sp=0x1a5beec pc=0x4180b0 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*http2ClientConn).readLoop(0x18d7208) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1a5bfe4 sp=0x1a5bfa4 pc=0x4175a4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*http2Transport).newClientConn.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1a5bfec sp=0x1a5bfe4 pc=0x4105f0 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1a5bfec sp=0x1a5bfec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*http2Transport).newClientConn in goroutine 105 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 47 gp=0x18857a8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c21ea8 sp=0x1c21e94 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1c21fa4, 0x1c21f6c, 0x0, 0x0, 0x5, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1c21f48 sp=0x1c21ea8 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x199e788) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1c21fe4 sp=0x1c21f48 pc=0x47408c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.newConn.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1c21fec sp=0x1c21fe4 pc=0x473c80 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c21fec sp=0x1c21fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by nhooyr.io/websocket.newConn in goroutine 45 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 84 gp=0x1909c28 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848b48, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1855d70 sp=0x1855d5c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848b38, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1855d88 sp=0x1855d70 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848b38, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1855d9c sp=0x1855d88 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1a50c48, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1855db0 sp=0x1855d9c pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x1a50c30, {0x19721e0, 0x3, 0x3}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1855df8 sp=0x1855db0 pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x1a50c30, {0x19721e0, 0x3, 0x3}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1855e24 sp=0x1855df8 pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x1ae8018, {0x19721e0, 0x3, 0x3}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1855e50 sp=0x1855e24 pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x1ae8018, {0x19721e0, 0x3, 0x3}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x1855e70 sp=0x1855e50 pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: io.ReadAtLeast({0x722604, 0x1ae8018}, {0x19721e0, 0x3, 0x3}, 0x3) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x1855e9c sp=0x1855e70 pc=0x1020b0 Nov 23 20:16:04 volumio go-librespot[1222]: io.ReadFull(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/ap.(*shannonConn).receivePacket(0x1a8d5c0) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/shannon.go:80 +0x130 fp=0x1855efc sp=0x1855e9c pc=0x48fedc Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/ap.(*Accesspoint).recvLoop(0x18b1cc8) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:251 +0x58 fp=0x1855fe4 sp=0x1855efc pc=0x48cda4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x28 fp=0x1855fec sp=0x1855fe4 pc=0x48cd20 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1855fec sp=0x1855fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 64 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x60 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 64 gp=0x1909d48 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1bc985c sp=0x1bc9848 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1bc9e34, 0x1bc9a3c, 0x0, 0x0, 0x6, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1bc98fc sp=0x1bc985c pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*AppPlayer).Run(0x19ee690, 0x1b30500) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:430 +0x1fc fp=0x1bc9fe0 sp=0x1bc98fc pc=0x57df50 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).withAppPlayer.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x30 fp=0x1bc9fec sp=0x1bc9fe0 pc=0x57944c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1bc9fec sp=0x1bc9fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by main.(*App).withAppPlayer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x348 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 65 gp=0x1909e68 m=nil [chan receive]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7c8, 0x19c41b0, 0xe, 0x7, 0x2) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c1df48 sp=0x1c1df34 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.chanrecv(0x19c4180, 0x1c1dfd0, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x1c1df84 sp=0x1c1df48 pc=0x1fdc4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.chanrecv2(0x19c4180, 0x1c1dfd0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x1c1df98 sp=0x1c1df84 pc=0x1f908 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).withAppPlayer.func1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x54 fp=0x1c1dfec sp=0x1c1df98 pc=0x57937c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c1dfec sp=0x1c1dfec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by main.(*App).withAppPlayer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 82 gp=0x1b42008 m=nil [chan receive, 4 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f7c8, 0x19c4130, 0xe, 0x7, 0x2) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c1e73c sp=0x1c1e728 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.chanrecv(0x19c4100, 0x1c1e7e0, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x1c1e778 sp=0x1c1e73c pc=0x1fdc4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.chanrecv2(0x19c4100, 0x1c1e7e0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x1c1e78c sp=0x1c1e778 pc=0x1f908 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*App).withAppPlayer.func2() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x1c1e7ec sp=0x1c1e78c pc=0x578f90 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c1e7ec sp=0x1c1e7ec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by main.(*App).withAppPlayer in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 83 gp=0x1b42128 m=nil [IO wait, 2 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848dc8, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1c1ede8 sp=0x1c1edd4 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848db8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1c1ee00 sp=0x1c1ede8 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848db8, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1c1ee14 sp=0x1c1ee00 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x19ee5b8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c1ee28 sp=0x1c1ee14 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Accept(0x19ee5a0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1c1ee70 sp=0x1c1ee28 pc=0x10c974 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).accept(0x19ee5a0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x1c1eed8 sp=0x1c1ee70 pc=0x2a2504 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPListener).accept(0x19dd9e0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x1c1eef0 sp=0x1c1eed8 pc=0x2ba604 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPListener).Accept(0x19dd9e0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x1c1ef0c sp=0x1c1eef0 pc=0x2b9670 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*onceCloseListener).Accept(0x1b28740) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x34 fp=0x1c1ef24 sp=0x1c1ef0c pc=0x45e914 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Server).Serve(0x18b1e08, {0x72527c, 0x19dd9e0}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x1c1efbc sp=0x1c1ef24 pc=0x43cfd0 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.Serve(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x1c1efec sp=0x1c1efbc pc=0x528d7c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1c1efec sp=0x1c1efec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 85 gp=0x1b42248 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848a08, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1d33938 sp=0x1d33924 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb58489f8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1d33950 sp=0x1d33938 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb58489f8, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1d33964 sp=0x1d33950 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1b93cd8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1d33978 sp=0x1d33964 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x1b93cc0, {0x19ff000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1d339c0 sp=0x1d33978 pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x1b93cc0, {0x19ff000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1d339ec sp=0x1d339c0 pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x182f420, {0x19ff000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1d33a18 sp=0x1d339ec pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x182f420, {0x19ff000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x1d33a38 sp=0x1d33a18 pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*atLeastReader).Read(0x1cd65d0, {0x19ff000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1d33a64 sp=0x1d33a38 pc=0x386018 Nov 23 20:16:04 volumio go-librespot[1222]: bytes.(*Buffer).ReadFrom(0x18b538c, {0x723a98, 0x1cd65d0}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1d33aa0 sp=0x1d33a64 pc=0x136d6c Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readFromUntil(0x18b5208, {0x722604, 0x182f420}, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1d33ac8 sp=0x1d33aa0 pc=0x386274 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecordOrCCS(0x18b5208, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1d33c2c sp=0x1d33ac8 pc=0x383974 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecord(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).Read(0x18b5208, {0x1b3a000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1d33c5c sp=0x1d33c2c pc=0x3897e4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*readWriteCloserBody).Read(0x1c60de0, {0x1b3a000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2429 +0xec fp=0x1d33c7c sp=0x1d33c5c pc=0x4538c0 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).fill(0x1b2c0f0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x1d33ca0 sp=0x1d33c7c pc=0x15a4b8 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).ReadByte(0x1b2c0f0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x1d33cac sp=0x1d33ca0 pc=0x15ad3c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.readFrameHeader(0x1b2c0f0, {0x199e678, 0x8, 0x8}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1d33cec sp=0x1d33cac pc=0x4771e0 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x199e648, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1d33dac sp=0x1d33cec pc=0x479e44 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).readLoop(0x199e648, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1d33e70 sp=0x1d33dac pc=0x47992c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).reader(0x199e648, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1d33efc sp=0x1d33e70 pc=0x47ab40 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).Reader(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).Read(0x199e648, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1d33f24 sp=0x1d33efc pc=0x479210 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/dealer.(*Dealer).recvLoop(0x1c7aaa8) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:153 +0x58 fp=0x1d33fe4 sp=0x1d33f24 pc=0x4b8a14 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:99 +0x28 fp=0x1d33fec sp=0x1d33fe4 pc=0x4ba26c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio volumio[978]: info: Nov 23 20:16:04 volumio volumio[978]: ---------------------------- USB Audio Device Detached Nov 23 20:16:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 23 20:16:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1d33fec sp=0x1d33fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 64 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:99 +0x60 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 45 gp=0x1c4a6c8 m=nil [IO wait, 4 minutes]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb58488c8, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x18578a4 sp=0x1857890 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb58488b8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x18578bc sp=0x18578a4 pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb58488b8, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x18578d0 sp=0x18578bc pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1a50068, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x18578e4 sp=0x18578d0 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x1a50050, {0x1aaf000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x185792c sp=0x18578e4 pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x1a50050, {0x1aaf000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1857958 sp=0x185792c pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x1ae8090, {0x1aaf000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1857984 sp=0x1857958 pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x1ae8090, {0x1aaf000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x18579a4 sp=0x1857984 pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: io.(*multiReader).Read(0x1bce100, {0x1aaf000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x18579d0 sp=0x18579a4 pc=0x103318 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).fill(0x1bbc330) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x18579f4 sp=0x18579d0 pc=0x15a4b8 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).ReadByte(0x1bbc330) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x1857a00 sp=0x18579f4 pc=0x15ad3c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.readFrameHeader(0x1bbc330, {0x199e7b8, 0x8, 0x8}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1857a40 sp=0x1857a00 pc=0x4771e0 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x199e788, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1857b00 sp=0x1857a40 pc=0x479e44 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).readLoop(0x199e788, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1857bc4 sp=0x1857b00 pc=0x47992c Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).reader(0x199e788, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1857c50 sp=0x1857bc4 pc=0x47ab40 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).Reader(...) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Nov 23 20:16:04 volumio go-librespot[1222]: nhooyr.io/websocket.(*Conn).Read(0x199e788, {0x725688, 0xad3b20}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1857c78 sp=0x1857c50 pc=0x479210 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*ApiServer).serve.func14({0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x22c fp=0x1857cd0 sp=0x1857c78 pc=0x570c88 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.HandlerFunc.ServeHTTP(0x1914148, {0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x1857ce0 sp=0x1857cd0 pc=0x4395b4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*ServeMux).ServeHTTP(0x1922000, {0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x1857d1c sp=0x1857ce0 pc=0x43b414 Nov 23 20:16:04 volumio go-librespot[1222]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x1857d40 sp=0x1857d1c pc=0x570a44 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.HandlerFunc.ServeHTTP(0x1918140, {0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x1857d50 sp=0x1857d40 pc=0x4395b4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.serverHandler.ServeHTTP({0x1920148}, {0x7253b0, 0x18b0288}, 0x18b0008) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x1857d6c sp=0x1857d50 pc=0x43cba4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*conn).serve(0x18ce3c0, {0x7256a8, 0x1928138}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x1857fdc sp=0x1857d6c pc=0x437df4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Server).Serve.gowrap3() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x1857fec sp=0x1857fdc pc=0x43d448 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1857fec sp=0x1857fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*Server).Serve in goroutine 19 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 16 gp=0x1c4aea8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1a56c9c sp=0x1a56c88 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1a56dfc, 0x1a56d68, 0x0, 0x0, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1a56d3c sp=0x1a56c9c pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/audio.(*KeyProvider).recvLoop(0x1a54918) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:52 +0x118 fp=0x1a56fe4 sp=0x1a56d3c pc=0x49552c Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x28 fp=0x1a56fec sp=0x1a56fe4 pc=0x4960c8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1a56fec sp=0x1a56fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 64 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x60 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 180 gp=0x1b427e8 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848be8, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1ba6afc sp=0x1ba6ae8 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848bd8, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1ba6b14 sp=0x1ba6afc pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848bd8, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1ba6b28 sp=0x1ba6b14 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1814608, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1ba6b3c sp=0x1ba6b28 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x18145f0, {0x1bac000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1ba6b84 sp=0x1ba6b3c pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x18145f0, {0x1bac000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1ba6bb0 sp=0x1ba6b84 pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x1b90008, {0x1bac000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1ba6bdc sp=0x1ba6bb0 pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x1b90008, {0x1bac000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x1ba6bfc sp=0x1ba6bdc pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*atLeastReader).Read(0x1cd6150, {0x1bac000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1ba6c28 sp=0x1ba6bfc pc=0x386018 Nov 23 20:16:04 volumio go-librespot[1222]: bytes.(*Buffer).ReadFrom(0x1b3c18c, {0x723a98, 0x1cd6150}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1ba6c64 sp=0x1ba6c28 pc=0x136d6c Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readFromUntil(0x1b3c008, {0x722604, 0x1b90008}, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1ba6c8c sp=0x1ba6c64 pc=0x386274 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecordOrCCS(0x1b3c008, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1ba6df0 sp=0x1ba6c8c pc=0x383974 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecord(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).Read(0x1b3c008, {0x1b81000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1ba6e20 sp=0x1ba6df0 pc=0x3897e4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).Read(0x1bc2218, {0x1b81000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x1ba6e54 sp=0x1ba6e20 pc=0x45138c Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).fill(0x1b2dcb0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x1ba6e78 sp=0x1ba6e54 pc=0x15a4b8 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).Peek(0x1b2dcb0, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:148 +0x68 fp=0x1ba6e84 sp=0x1ba6e78 pc=0x15a63c Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).readLoop(0x1bc2218) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2141 +0x194 fp=0x1ba6fe4 sp=0x1ba6e84 pc=0x451f1c Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Transport).dialConn.gowrap2() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x28 fp=0x1ba6fec sp=0x1ba6fe4 pc=0x45083c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1ba6fec sp=0x1ba6fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*Transport).dialConn in goroutine 146 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x13f4 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 166 gp=0x19e10e8 m=nil [sync.Cond.Wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f938, 0x19e6ed0, 0x14, 0x4, 0x3) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1858f20 sp=0x1858f0c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goparkunlock(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Nov 23 20:16:04 volumio go-librespot[1222]: sync.runtime_notifyListWait(0x19e6ec8, 0x431f) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sema.go:569 +0x1e4 fp=0x1858f4c sp=0x1858f20 pc=0x90a94 Nov 23 20:16:04 volumio go-librespot[1222]: sync.(*Cond).Wait(0x19e6ec0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/sync/cond.go:70 +0xbc fp=0x1858f70 sp=0x1858f4c pc=0x9cc44 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.(*RingBuffer[...]).PutWait(0x726460, {0x1b59500, 0x1ba, 0x1ba}) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/ring_buffer.go:50 +0x8c fp=0x1858f98 sp=0x1858f70 pc=0x49df68 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.(*output).readLoop(0x1a36a80) Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:252 +0xb8 fp=0x1858fd0 sp=0x1858f98 pc=0x49abd4 Nov 23 20:16:04 volumio go-librespot[1222]: go-librespot/output.newOutput.func2() Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:93 +0x20 fp=0x1858fec sp=0x1858fd0 pc=0x498ec4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1858fec sp=0x1858fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by go-librespot/output.newOutput in goroutine 62 Nov 23 20:16:04 volumio go-librespot[1222]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:92 +0x3d8 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 181 gp=0x194c008 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1a59eb0 sp=0x1a59e9c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x1a59fac, 0x1a59f78, 0x0, 0x0, 0x2, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x1a59f50 sp=0x1a59eb0 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).writeLoop(0x1bc2218) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x1a59fe4 sp=0x1a59f50 pc=0x4539dc Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Transport).dialConn.gowrap3() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x1a59fec sp=0x1a59fe4 pc=0x4507e8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1a59fec sp=0x1a59fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*Transport).dialConn in goroutine 146 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 13843 gp=0x19d86c8 m=nil [select]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x254beb0 sp=0x254be9c pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.selectgo(0x254bfac, 0x254bf78, 0x0, 0x0, 0x2, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x254bf50 sp=0x254beb0 pc=0x705e4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).writeLoop(0x1ab0218) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x254bfe4 sp=0x254bf50 pc=0x4539dc Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Transport).dialConn.gowrap3() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x254bfec sp=0x254bfe4 pc=0x4507e8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x254bfec sp=0x254bfec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*Transport).dialConn in goroutine 13071 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 13842 gp=0x1b82248 m=nil [IO wait]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.gopark(0x68f928, 0xb5848e68, 0x2, 0x2, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x1ba4afc sp=0x1ba4ae8 pc=0x5c82c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpollblock(0xb5848e58, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x1ba4b14 sp=0x1ba4afc pc=0x540f0 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.runtime_pollWait(0xb5848e58, 0x72) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x1ba4b28 sp=0x1ba4b14 pc=0x8eba8 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).wait(0x1814f18, 0x72, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1ba4b3c sp=0x1ba4b28 pc=0x108108 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*pollDesc).waitRead(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Nov 23 20:16:04 volumio go-librespot[1222]: internal/poll.(*FD).Read(0x1814f00, {0x1d74000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1ba4b84 sp=0x1ba4b3c pc=0x1092f4 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*netFD).Read(0x1814f00, {0x1d74000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x1ba4bb0 sp=0x1ba4b84 pc=0x2a0610 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*conn).Read(0x1ae9c80, {0x1d74000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x1ba4bdc sp=0x1ba4bb0 pc=0x2b07f0 Nov 23 20:16:04 volumio go-librespot[1222]: net.(*TCPConn).Read(0x1ae9c80, {0x1d74000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: :1 +0x44 fp=0x1ba4bfc sp=0x1ba4bdc pc=0x2c3a44 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*atLeastReader).Read(0x1cd6350, {0x1d74000, 0xa000, 0xa000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x1ba4c28 sp=0x1ba4bfc pc=0x386018 Nov 23 20:16:04 volumio go-librespot[1222]: bytes.(*Buffer).ReadFrom(0x18b4a8c, {0x723a98, 0x1cd6350}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x1ba4c64 sp=0x1ba4c28 pc=0x136d6c Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readFromUntil(0x18b4908, {0x722604, 0x1ae9c80}, 0x5) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x1ba4c8c sp=0x1ba4c64 pc=0x386274 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecordOrCCS(0x18b4908, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x1ba4df0 sp=0x1ba4c8c pc=0x383974 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).readRecord(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Nov 23 20:16:04 volumio go-librespot[1222]: crypto/tls.(*Conn).Read(0x18b4908, {0x1ab4000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x1ba4e20 sp=0x1ba4df0 pc=0x3897e4 Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).Read(0x1ab0218, {0x1ab4000, 0x1000, 0x1000}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x1ba4e54 sp=0x1ba4e20 pc=0x45138c Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).fill(0x1b9eb40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x1ba4e78 sp=0x1ba4e54 pc=0x15a4b8 Nov 23 20:16:04 volumio go-librespot[1222]: bufio.(*Reader).Peek(0x1b9eb40, 0x1) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:148 +0x68 fp=0x1ba4e84 sp=0x1ba4e78 pc=0x15a63c Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*persistConn).readLoop(0x1ab0218) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2141 +0x194 fp=0x1ba4fe4 sp=0x1ba4e84 pc=0x451f1c Nov 23 20:16:04 volumio go-librespot[1222]: net/http.(*Transport).dialConn.gowrap2() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x28 fp=0x1ba4fec sp=0x1ba4fe4 pc=0x45083c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit({}) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x1ba4fec sp=0x1ba4fec pc=0x9346c Nov 23 20:16:04 volumio go-librespot[1222]: created by net/http.(*Transport).dialConn in goroutine 13071 Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x13f4 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0xb4cfdb3c Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x8 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0xb6fb0968 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0xb4cfdb3c Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xaf Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x47 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xb6f3ca3c Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1884128 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x8 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb4cfdb38 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0xffffffff Nov 23 20:16:04 volumio go-librespot[1222]: pc 0xb6c51fb4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0x10 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=0 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0xaa9290 m=0 mp=0xaa9b38 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0xaa9c00, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xbed07a64 sp=0xbed07a64 pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0xaa9c00, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xbed07a8c sp=0xbed07a64 pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0xaa9c00) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xbed07aac sp=0xbed07a8c pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mPark(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.stopm() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xbed07ac0 sp=0xbed07aac pc=0x61498 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.findRunnable() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xbed07b6c sp=0xbed07ac0 pc=0x63138 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.schedule() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xbed07b88 sp=0xbed07b6c pc=0x64688 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.park_m(0x19e10e8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xbed07ba8 sp=0xbed07b88 pc=0x64f00 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0x720d40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xbed07bb0 sp=0xbed07ba8 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0xaa9c00 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xaab838 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0xaa9290 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x65049c0a Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xbed07a64 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a8 m=2 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x18026c8 m=2 mp=0x1846588 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0xaab8c4, 0x80, 0x0, 0xb68c4d48, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:417 +0x20 fp=0xb68c4d28 sp=0xb68c4d28 pc=0x944a8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0xaab8c4, 0x0, 0x645be2c40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:75 +0x178 fp=0xb68c4d50 sp=0xb68c4d28 pc=0x551c0 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notetsleep_internal(0xaab8c4, 0x645be2c40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:212 +0x124 fp=0xb68c4d78 sp=0xb68c4d50 pc=0x25418 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notetsleep(0xaab8c4, 0x645be2c40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:235 +0x48 fp=0xb68c4d8c sp=0xb68c4d78 pc=0x255b8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.sysmon() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:6001 +0x2e0 fp=0xb68c4de8 sp=0xb68c4d8c pc=0x69f84 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart1() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1729 +0x7c fp=0xb68c4df8 sp=0xb68c4de8 pc=0x5f8c8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart0() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1686 +0x7c fp=0xb68c4e0c sp=0xb68c4df8 pc=0x5f83c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:210 +0x8 fp=0xb68c4e10 sp=0xb68c4e0c pc=0x91740 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0xfffffffc Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0xb68c4d48 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x18026c8 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x3b9aca00 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb68c4d28 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x551c0 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a8 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0x60000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=3 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x1802a28 m=3 mp=0x1846b08 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0x1846bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb60c3d6c sp=0xb60c3d6c pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0x1846bd0, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb60c3d94 sp=0xb60c3d6c pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0x1846bd0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb60c3db4 sp=0xb60c3d94 pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mPark(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.stopm() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb60c3dc8 sp=0xb60c3db4 pc=0x61498 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.exitsyscall0(0x1c4b8c8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4755 +0x26c fp=0xb60c3df0 sp=0xb60c3dc8 pc=0x669a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0xb6b78f40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb60c3df8 sp=0xb60c3df0 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0x1846bd0 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1c4b910 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x1846b08 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1802a28 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb60c3d6c Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=4 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x1802ea8 m=4 mp=0x1847088 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0x1847150, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb54fecac sp=0xb54fecac pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0x1847150, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb54fecd4 sp=0xb54fecac pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0x1847150) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb54fecf4 sp=0xb54fecd4 pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mPark(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.stopm() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb54fed08 sp=0xb54fecf4 pc=0x61498 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.findRunnable() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb54fedb4 sp=0xb54fed08 pc=0x63138 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.schedule() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb54fedd0 sp=0xb54fedb4 pc=0x64688 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.park_m(0x19099e8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xb54fedf0 sp=0xb54fedd0 pc=0x64f00 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0xb6b78f40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb54fedf8 sp=0xb54fedf0 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0x1847150 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xaab838 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1802ea8 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x65c69350 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb54fecac Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=6 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x1908128 m=6 mp=0x1904008 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0xad429c, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb42fed94 sp=0xb42fed94 pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0xad429c, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb42fedbc sp=0xb42fed94 pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0xad429c) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb42feddc sp=0xb42fedbc pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.templateThread() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2760 +0x90 fp=0xb42fede8 sp=0xb42feddc pc=0x61350 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart1() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1729 +0x7c fp=0xb42fedf8 sp=0xb42fede8 pc=0x5f8c8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart0() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1686 +0x7c fp=0xb42fee0c sp=0xb42fedf8 pc=0x5f83c Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mstart() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:210 +0x8 fp=0xb42fee10 sp=0xb42fee0c pc=0x91740 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0xad429c Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0xbed079da Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xb42ff440 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1908128 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0xb3900470 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb42fed94 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=7 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x19086c8 m=7 mp=0x1905088 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0x1905150, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb38fecc4 sp=0xb38fecc4 pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0x1905150, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb38fecec sp=0xb38fecc4 pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0x1905150) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb38fed0c sp=0xb38fecec pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mPark(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.stopm() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb38fed20 sp=0xb38fed0c pc=0x61498 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.findRunnable() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb38fedcc sp=0xb38fed20 pc=0x63138 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.schedule() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb38fede8 sp=0xb38fedcc pc=0x64688 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit0(0x1942908) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4181 +0x20 fp=0xb38fedf0 sp=0xb38fede8 pc=0x65548 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0xb6b78f40) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb38fedf8 sp=0xb38fedf0 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0x1905150 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1810054 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xaab838 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x19086c8 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x4 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb38fecc4 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x1b370 m=8 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x1b42488 m=8 mp=0x1904b08 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime/internal/syscall.Syscall6(0x15a, 0x4, 0xb2efe4e8, 0x80, 0x7512, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/internal/syscall/asm_linux_arm.s:17 +0x20 fp=0xb2efe46c sp=0xb2efe46c pc=0x1b370 Nov 23 20:16:04 volumio go-librespot[1222]: runtime/internal/syscall.EpollWait(0x4, {0xb2efe4e8, 0x80, 0x80}, 0x80, 0x7512) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/internal/syscall/syscall_linux.go:55 +0x60 fp=0xb2efe49c sp=0xb2efe46c pc=0x1b2c8 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.netpoll(0x6fa5ae44f) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll_epoll.go:118 +0x17c fp=0xb2efed08 sp=0xb2efe49c pc=0x54ce4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.findRunnable() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3470 +0xbbc fp=0xb2efedb4 sp=0xb2efed08 pc=0x62ef0 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.schedule() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb2efedd0 sp=0xb2efedb4 pc=0x64688 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.park_m(0x1b42248) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xb2efedf0 sp=0xb2efedd0 pc=0x64f00 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0xb60c4900) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb2efedf8 sp=0xb2efedf0 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0xfffffffc Nov 23 20:16:04 volumio go-librespot[1222]: r1 0xb2efe4e8 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x7512 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0x15a Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1b42488 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0xa6b0fc Nov 23 20:16:04 volumio go-librespot[1222]: ip 0xb12ee7c4 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb2efe46c Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x1b2c8 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x1b370 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0x20000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: ----- Nov 23 20:16:04 volumio go-librespot[1222]: SIGQUIT: quit Nov 23 20:16:04 volumio go-librespot[1222]: PC=0x944a4 m=9 sigcode=0 Nov 23 20:16:04 volumio go-librespot[1222]: goroutine 0 gp=0x1c4ab48 m=9 mp=0x1905608 [idle]: Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futex(0x19056d0, 0x80, 0x0, 0x0, 0x0, 0x0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb24fecc4 sp=0xb24fecc4 pc=0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.futexsleep(0x19056d0, 0x0, 0xffffffffffffffff) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb24fecec sp=0xb24fecc4 pc=0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.notesleep(0x19056d0) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb24fed0c sp=0xb24fecec pc=0x25294 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mPark(...) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.stopm() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb24fed20 sp=0xb24fed0c pc=0x61498 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.findRunnable() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb24fedcc sp=0xb24fed20 pc=0x63138 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.schedule() Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb24fede8 sp=0xb24fedcc pc=0x64688 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.goexit0(0x1942fc8) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4181 +0x20 fp=0xb24fedf0 sp=0xb24fede8 pc=0x65548 Nov 23 20:16:04 volumio go-librespot[1222]: runtime.mcall(0xb4cfe900) Nov 23 20:16:04 volumio go-librespot[1222]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb24fedf8 sp=0xb24fedf0 pc=0x917a0 Nov 23 20:16:04 volumio go-librespot[1222]: trap 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: error 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: oldmask 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r0 0x19056d0 Nov 23 20:16:04 volumio go-librespot[1222]: r1 0x80 Nov 23 20:16:04 volumio go-librespot[1222]: r2 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r3 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r4 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r5 0x0 Nov 23 20:16:04 volumio go-librespot[1222]: r6 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r7 0xf0 Nov 23 20:16:04 volumio go-librespot[1222]: r8 0x1 Nov 23 20:16:04 volumio go-librespot[1222]: r9 0xaab838 Nov 23 20:16:04 volumio go-librespot[1222]: r10 0x1c4ab48 Nov 23 20:16:04 volumio go-librespot[1222]: fp 0x6 Nov 23 20:16:04 volumio go-librespot[1222]: ip 0x610e55d7 Nov 23 20:16:04 volumio go-librespot[1222]: sp 0xb24fecc4 Nov 23 20:16:04 volumio go-librespot[1222]: lr 0x550a4 Nov 23 20:16:04 volumio go-librespot[1222]: pc 0x944a4 Nov 23 20:16:04 volumio go-librespot[1222]: cpsr 0xa0000010 Nov 23 20:16:04 volumio go-librespot[1222]: fault 0x0 Nov 23 20:16:04 volumio kernel: usb 1-1.2: new high-speed USB device number 4 using xhci_hcd Nov 23 20:16:04 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.03 Nov 23 20:16:04 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 23 20:16:04 volumio kernel: usb 1-1.2: Product: D50s Nov 23 20:16:04 volumio kernel: usb 1-1.2: Manufacturer: Topping Nov 23 20:16:04 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Nov 23 20:16:04 volumio volumio[978]: info: Nov 23 20:16:04 volumio volumio[978]: ---------------------------- USB Audio Device Attached Nov 23 20:16:04 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 23 20:16:04 volumio go-librespot[1222]: Aborted Nov 23 20:16:04 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Nov 23 20:16:04 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 23 20:16:04 volumio volumio[978]: info: Connection to go-librespot Websocket closed Nov 23 20:16:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 23 20:16:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 23 20:16:07 volumio volumio[978]: info: Discovery: Getting this device information Nov 23 20:16:07 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:16:07 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 23 20:16:07 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 23 20:16:07 volumio volumio[978]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 23 20:16:08 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 23 20:16:08 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 23 20:16:08 volumio systemd[1]: Stopped go-librespot Daemon. Nov 23 20:16:08 volumio systemd[1]: Started go-librespot Daemon. Nov 23 20:16:08 volumio go-librespot[2008]: Librespot-go daemon starting... Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=info msg="generated new device id: 2c98f1687cdf868786e87b2bb8ff3ccd07c7c883" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="stored credentials found for 11130418669" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="zeroconf server listening on port 36827" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="obtained new client token: AAA/8WEjAv5zarKpcPfXu2kLHr04ljPcMjvm0p1jRcRcKLc+sxfHcKHbEZL4uyrJm1TNtPU2okstVmabgLXg3llz4So+TnF4mfD2GOip78ZOoboSElz3x/GoixwU1BpdFkUFcpRgc0G0VAvFyQt44wqMEadpHXHrrABZWc6rp5EugHeRBBaHyCFV0iJfEEXkn+blZdlV+GRXT0xtnvg5uVkJutEs7B7veJnqosuu0Mx7nwLbfW3ZGnP/utVlqH0=" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=info msg="connected to ap-gew4.spotify.com:4070" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="completed keyexchange" Nov 23 20:16:08 volumio go-librespot[2008]: time="2024-11-23T20:16:08Z" level=debug msg="completed challenge" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="authenticated as 11130418669" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="authenticated as 11130418669" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="dealer connection opened" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="initializing zeroconf session, username: 11130418669" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="autoplay enabled: false" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="received connection id: YWY0NjdkNDAtYzJjMS00MjFmLTgwNTMtYTNkODdlODQxYjcyK2RlYWxlcit0Y3A6Ly8wYWNhNTg0OC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArRjU4RkVBODkxQkYwMzFDRjdGMTM2QTk2QzBFNTFBNTM4Mjg3QjkxNjBDRDBDNTY1N0UwOEM2NkQzOTY5M0Q0NQ==" Nov 23 20:16:09 volumio go-librespot[2008]: time="2024-11-23T20:16:09Z" level=debug msg="put connect state because NEW_DEVICE" Nov 23 20:16:10 volumio kernel: usb 1-1.2: USB disconnect, device number 4 Nov 23 20:16:10 volumio volumio[978]: info: Nov 23 20:16:10 volumio volumio[978]: ---------------------------- USB Audio Device Detached Nov 23 20:16:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 23 20:16:10 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:10 volumio kernel: usb 1-1.2: new high-speed USB device number 5 using xhci_hcd Nov 23 20:16:10 volumio volumio[978]: info: Initializing connection to go-librespot Websocket Nov 23 20:16:10 volumio go-librespot[2008]: time="2024-11-23T20:16:10Z" level=debug msg="new websocket client" Nov 23 20:16:10 volumio volumio[978]: info: Connection to go-librespot Websocket established Nov 23 20:16:10 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.03 Nov 23 20:16:10 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 23 20:16:10 volumio kernel: usb 1-1.2: Product: D50s Nov 23 20:16:10 volumio kernel: usb 1-1.2: Manufacturer: Topping Nov 23 20:16:11 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Nov 23 20:16:11 volumio volumio[978]: info: Nov 23 20:16:11 volumio volumio[978]: ---------------------------- USB Audio Device Attached Nov 23 20:16:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 23 20:16:11 volumio kernel: usb 1-1.2: USB disconnect, device number 5 Nov 23 20:16:11 volumio volumio[978]: info: Nov 23 20:16:11 volumio volumio[978]: ---------------------------- USB Audio Device Detached Nov 23 20:16:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Nov 23 20:16:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:11 volumio kernel: usb 1-1.2: new high-speed USB device number 6 using xhci_hcd Nov 23 20:16:11 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.03 Nov 23 20:16:11 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Nov 23 20:16:11 volumio kernel: usb 1-1.2: Product: D50s Nov 23 20:16:11 volumio kernel: usb 1-1.2: Manufacturer: Topping Nov 23 20:16:11 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Nov 23 20:16:11 volumio volumio[978]: info: Nov 23 20:16:11 volumio volumio[978]: ---------------------------- USB Audio Device Attached Nov 23 20:16:11 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Nov 23 20:16:13 volumio volumio[978]: info: Getting Spotify volume Nov 23 20:16:13 volumio volumio[978]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 23 20:16:14 volumio volumio[978]: info: Spotify volume: 100 Nov 23 20:16:14 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:16:28 volumio volumio[978]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Nov 23 20:16:28 volumio volumio[978]: info: Preparing to save Alsa Options, stopping services first Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::volumioGetState Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::volumioPause Nov 23 20:16:28 volumio volumio[978]: info: CoreStateMachine::pause Nov 23 20:16:28 volumio volumio[978]: info: CoreStateMachine::stPlaybackTimer Nov 23 20:16:28 volumio volumio[978]: info: CoreStateMachine::servicePause Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::servicePause Nov 23 20:16:28 volumio volumio[978]: info: Spotify Received pause Nov 23 20:16:28 volumio volumio[978]: SPOTIFY: SPOTIFY PAUSE Nov 23 20:16:28 volumio volumio[978]: SPOTIFY: {"status":"play","title":"Cold Little Heart - Radio Edit","artist":"Michael Kiwanuka","album":"Cold Little Heart (Radio Edit)","albumart":"https://i.scdn.co/image/ab67616d00001e02d38e121683bc208a4530fb22","uri":"spotify:track:7KX65PC1UZuImsUInThbav","trackType":"spotify","codec":"ogg","seek":47001,"duration":210,"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,"updatedb":false,"volatile":true,"service":"spop"} Nov 23 20:16:28 volumio volumio[978]: info: Sending Spotify command to local API: /player/pause Nov 23 20:16:28 volumio volumio[978]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"snd_allo_digione"},"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"}} Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: Enabling I2S DAC: Allo DigiOne Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Nov 23 20:16:28 volumio sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:28 volumio sudo[2070]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:28 volumio volumio[978]: info: No Overlays Loaded Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio sudo[2074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Nov 23 20:16:28 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/#sound-dai-cells Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/compatible Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/reg Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/PVDD-supply Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/DVDD-supply Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/status Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/wlf,reset-gpio Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Nov 23 20:16:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Nov 23 20:16:28 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: Device 2 does not have any Mixer Control Available Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 23 20:16:28 volumio volumio[978]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 23 20:16:28 volumio volumio[978]: info: Disabling external Volume Control Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Nov 23 20:16:28 volumio volumio[978]: info: CoreStateMachine::pushState Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::volumioPushState Nov 23 20:16:28 volumio volumio[978]: info: Preparing to generate the ALSA configuration file Nov 23 20:16:28 volumio volumio[978]: info: I2S Param [object Object] successfully enabled Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 23 20:16:28 volumio volumio[978]: info: Getting Alsa Cards List without I2S DAC Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Nov 23 20:16:28 volumio volumio[978]: info: Asound.conf file unchanged, so no further update is needed Nov 23 20:16:28 volumio volumio[978]: info: Output device has changed, restarting MPD Nov 23 20:16:28 volumio sudo[2100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 23 20:16:28 volumio sudo[2100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:28 volumio volumio[978]: info: Output device has changed, restarting Shairport Sync Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio sudo[2100]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:28 volumio sudo[2103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 23 20:16:28 volumio sudo[2103]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:28 volumio systemd[1]: Stopping Music Player Daemon... Nov 23 20:16:28 volumio volumio[978]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 23 20:16:28 volumio volumio[978]: info: MPD Permissions set Nov 23 20:16:28 volumio systemd[1]: mpd.service: Succeeded. Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio systemd[1]: Stopped Music Player Daemon. Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 23 20:16:28 volumio volumio[978]: info: Starting Shairport Sync Nov 23 20:16:28 volumio sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 23 20:16:28 volumio sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:29 volumio systemd[1]: Starting Music Player Daemon... Nov 23 20:16:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 23 20:16:29 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 23 20:16:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 23 20:16:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 23 20:16:29 volumio sudo[2114]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:29 volumio volumio[978]: info: Shairport-Sync Started Nov 23 20:16:29 volumio sudo[2108]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 23 20:16:29 volumio sudo[2108]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 23 20:16:29 volumio sudo[2108]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:29 volumio mpd[2154]: Nov 23 20:16 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 23 20:16:29 volumio systemd[1]: Started Music Player Daemon. Nov 23 20:16:29 volumio sudo[2103]: pam_unix(sudo:session): session closed for user root Nov 23 20:16:29 volumio volumio[978]: error: updateQueue error: null Nov 23 20:16:29 volumio volumio[978]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Nov 23 20:16:29 volumio volumio[978]: info: PLUGIN onReboot : networkfs Nov 23 20:16:29 volumio volumio[978]: info: PLUGIN onReboot : audiophonicsonoff Nov 23 20:16:29 volumio volumio[978]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:16:29 volumio volumio[978]: TypeError: Cannot read property 'writeSync' of undefined Nov 23 20:16:29 volumio volumio[978]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Nov 23 20:16:29 volumio volumio[978]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Nov 23 20:16:29 volumio volumio[978]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Nov 23 20:16:29 volumio volumio[978]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 23 20:16:29 volumio volumio[978]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 23 20:16:29 volumio volumio[978]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Nov 23 20:16:29 volumio volumio[978]: at CoreCommandRouter.reboot (/volumio/app/index.js:1345:22) Nov 23 20:16:29 volumio volumio[978]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Nov 23 20:16:29 volumio volumio[978]: at Socket.emit (events.js:315:20) Nov 23 20:16:29 volumio volumio[978]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 23 20:16:29 volumio volumio[978]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Nov 23 20:16:29 volumio volumio[978]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 23 20:16:30 volumio sudo[2170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-23 20:15 Nov 23 20:16:30 volumio sudo[2170]: 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="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"