-- Logs begin at Tue 2025-06-03 07:14:09 UTC, end at Mon 2025-06-23 21:22:35 UTC. -- Jun 23 21:21:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 21:21:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 21:21:01 volumio volumio[1000]: info: Discovery: Getting this device information Jun 23 21:21:01 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:21:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 21:21:02 volumio volumio[1000]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 23 21:21:02 volumio volumio[1000]: info: Preparing to save Alsa Options, stopping services first Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::volumioPause Jun 23 21:21:02 volumio volumio[1000]: info: CoreStateMachine::pause Jun 23 21:21:02 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:21:02 volumio volumio[1000]: info: CoreStateMachine::servicePause Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::servicePause Jun 23 21:21:02 volumio volumio[1000]: info: Spotify Received pause Jun 23 21:21:02 volumio volumio[1000]: SPOTIFY: SPOTIFY PAUSE Jun 23 21:21:02 volumio volumio[1000]: SPOTIFY: {"status":"play","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","codec":"ogg","seek":1,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":77,"dbVolume":null,"mute":false,"disableVolumeControl":false,"stream":false,"volatile":true,"service":"spop"} Jun 23 21:21:02 volumio volumio[1000]: info: Sending Spotify command to local API: /player/pause Jun 23 21:21:02 volumio volumio[1000]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"snd_allo_digione"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:02 volumio volumio[1000]: info: Device 2 does not have any Mixer Control Available Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 23 21:21:02 volumio volumio[1000]: info: Updating Volume Controller Parameters: Device: 2 Name: snd_allo_digione Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 23 21:21:02 volumio volumio[1000]: info: Disabling external Volume Control Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 21:21:02 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:02 volumio volumio[1000]: info: Preparing to generate the ALSA configuration file Jun 23 21:21:02 volumio go-librespot[6384]: time="2025-06-23T21:21:02Z" level=debug msg="pause track at 63183ms" Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:21:02 volumio go-librespot[6384]: time="2025-06-23T21:21:02Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 23 21:21:02 volumio volumio[1000]: info: Asound.conf file written Jun 23 21:21:02 volumio sudo[14579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Jun 23 21:21:02 volumio sudo[14579]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio sudo[14579]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:02 volumio go-librespot[6384]: time="2025-06-23T21:21:02Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:02 volumio go-librespot[6384]: time="2025-06-23T21:21:02Z" level=trace msg="emitting websocket event: paused" Jun 23 21:21:02 volumio volumio[1000]: info: Output device has changed, restarting MPD Jun 23 21:21:02 volumio sudo[14584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 21:21:02 volumio sudo[14584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio sudo[14584]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:02 volumio volumio[1000]: info: Output device has changed, restarting Shairport Sync Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:02 volumio sudo[14587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 21:21:02 volumio sudo[14587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio systemd[1]: Stopping Music Player Daemon... Jun 23 21:21:02 volumio volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 23 21:21:02 volumio volumio[1000]: info: Output device has changed, restarting MPD Jun 23 21:21:02 volumio systemd[1]: mpd.service: Succeeded. Jun 23 21:21:02 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 21:21:02 volumio sudo[14594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 21:21:02 volumio sudo[14594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio volumio[1000]: info: Output device has changed, restarting Shairport Sync Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:02 volumio sudo[14594]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:02 volumio systemd[1]: Starting Music Player Daemon... Jun 23 21:21:02 volumio sudo[14597]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 21:21:02 volumio sudo[14597]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 23 21:21:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jun 23 21:21:02 volumio systemd[1]: mpd.service: Succeeded. Jun 23 21:21:02 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 21:21:02 volumio systemd[1]: Starting Music Player Daemon... Jun 23 21:21:02 volumio volumio[1000]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","play_origin":"playlist"}} Jun 23 21:21:02 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:02 volumio volumio[1000]: SPOTIFY: {"status":"pause","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":63001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:02 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:02 volumio volumio[1000]: info: MPD Permissions set Jun 23 21:21:02 volumio volumio[1000]: info: MPD Permissions set Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:02 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:02 volumio sudo[14602]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 23 21:21:02 volumio volumio[1000]: info: Starting Shairport Sync Jun 23 21:21:02 volumio sudo[14602]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio sudo[14602]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:02 volumio volumio[1000]: info: Starting Shairport Sync Jun 23 21:21:02 volumio sudo[14614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 23 21:21:02 volumio sudo[14614]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 23 21:21:02 volumio sudo[14617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 23 21:21:02 volumio sudo[14617]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:02 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 23 21:21:02 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:03 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 23 21:21:03 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jun 23 21:21:03 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 23 21:21:03 volumio sudo[14614]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:03 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:03 volumio volumio[1000]: info: Shairport-Sync Started Jun 23 21:21:03 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:03 volumio sudo[14617]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:03 volumio volumio[1000]: info: Shairport-Sync Started Jun 23 21:21:03 volumio volumio[1000]: (node:1000) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 23 21:21:03 volumio volumio[1000]: (Use `node --trace-warnings ...` to show where the warning was created) Jun 23 21:21:03 volumio volumio[1000]: (node:1000) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 23 21:21:03 volumio volumio[1000]: (node:1000) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 23 21:21:03 volumio volumio[1000]: (node:1000) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 23 21:21:03 volumio volumio[1000]: (node:1000) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Jun 23 21:21:03 volumio mpd[14613]: Jun 23 21:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 23 21:21:03 volumio systemd[1]: Started Music Player Daemon. Jun 23 21:21:03 volumio sudo[14597]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:03 volumio sudo[14587]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:03 volumio volumio[1000]: error: MPD error: The expression evaluated to a falsy value: Jun 23 21:21:03 volumio volumio[1000]: assert.ok(self.idling) Jun 23 21:21:03 volumio volumio[1000]: error: The expression evaluated to a falsy value: Jun 23 21:21:03 volumio volumio[1000]: assert.ok(self.idling) Jun 23 21:21:03 volumio volumio[1000]: error: updateQueue error: null Jun 23 21:21:06 volumio volumio[1000]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 23 21:21:06 volumio volumio[1000]: info: CoreStateMachine::getcurrentVolume Jun 23 21:21:06 volumio volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume Jun 23 21:21:06 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:06 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::volumioVolatilePlay Jun 23 21:21:12 volumio volumio[1000]: info: CoreStateMachine::volatilePlay Jun 23 21:21:12 volumio volumio[1000]: info: Spotify Play Jun 23 21:21:12 volumio volumio[1000]: info: Sending Spotify command to local API: /player/resume Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=trace msg="seek to 63183ms (diff: 288ms, samples: 2786370, bytes: 2508151)" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" 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" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=debug msg="resume track at 62895ms" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=trace msg="scheduling prefetch in 125s" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:12 volumio go-librespot[6384]: time="2025-06-23T21:21:12Z" level=trace msg="emitting websocket event: playing" Jun 23 21:21:12 volumio volumio[1000]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","play_origin":"playlist"}} Jun 23 21:21:12 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:12 volumio volumio[1000]: SPOTIFY: {"status":"play","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":63001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:12 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:12 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:12 volumio volumio[1000]: SPOTIFY: {"status":"play","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":63001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:12 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:12 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:14 volumio go-librespot[6384]: time="2025-06-23T21:21:14Z" level=debug msg="fetched chunk 8/16, size: 524288" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:21:28 volumio go-librespot[6384]: time="2025-06-23T21:21:28Z" level=debug msg="fetched chunk 9/16, size: 524288" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:21:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 21:21:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 21:21:31 volumio volumio[1000]: info: Discovery: Getting this device information Jun 23 21:21:31 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:21:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 21:21:34 volumio volumio[1000]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 23 21:21:34 volumio volumio[1000]: info: Preparing to save Alsa Options, stopping services first Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPause Jun 23 21:21:34 volumio volumio[1000]: info: CoreStateMachine::pause Jun 23 21:21:34 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:21:34 volumio volumio[1000]: info: CoreStateMachine::servicePause Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::servicePause Jun 23 21:21:34 volumio volumio[1000]: info: Spotify Received pause Jun 23 21:21:34 volumio volumio[1000]: SPOTIFY: SPOTIFY PAUSE Jun 23 21:21:34 volumio volumio[1000]: SPOTIFY: {"status":"play","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","codec":"ogg","seek":63001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"} Jun 23 21:21:34 volumio volumio[1000]: info: Sending Spotify command to local API: /player/pause Jun 23 21:21:34 volumio volumio[1000]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"snd_allo_digione"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:34 volumio volumio[1000]: info: Device 2 does not have any Mixer Control Available Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 23 21:21:34 volumio volumio[1000]: info: Updating Volume Controller Parameters: Device: 2 Name: snd_allo_digione Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 23 21:21:34 volumio volumio[1000]: info: Disabling external Volume Control Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 21:21:34 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:34 volumio volumio[1000]: info: Preparing to generate the ALSA configuration file Jun 23 21:21:34 volumio volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Jun 23 21:21:34 volumio volumio[1000]: info: Output device has changed, restarting MPD Jun 23 21:21:34 volumio go-librespot[6384]: time="2025-06-23T21:21:34Z" level=debug msg="pause track at 85089ms" Jun 23 21:21:34 volumio sudo[14691]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 21:21:34 volumio sudo[14691]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:34 volumio volumio[1000]: info: Output device has changed, restarting Shairport Sync Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio sudo[14691]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:34 volumio sudo[14694]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 21:21:34 volumio sudo[14694]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:34 volumio systemd[1]: Stopping Music Player Daemon... Jun 23 21:21:34 volumio volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 23 21:21:34 volumio systemd[1]: mpd.service: Succeeded. Jun 23 21:21:34 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 21:21:34 volumio go-librespot[6384]: time="2025-06-23T21:21:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:21:34 volumio systemd[1]: Starting Music Player Daemon... Jun 23 21:21:34 volumio go-librespot[6384]: time="2025-06-23T21:21:34Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:34 volumio go-librespot[6384]: time="2025-06-23T21:21:34Z" level=trace msg="emitting websocket event: paused" Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 23 21:21:34 volumio volumio[1000]: info: MPD Permissions set Jun 23 21:21:34 volumio volumio[1000]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","play_origin":"playlist"}} Jun 23 21:21:34 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:34 volumio volumio[1000]: SPOTIFY: {"status":"pause","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":85001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:34 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio sudo[14699]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 23 21:21:34 volumio sudo[14699]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:21:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:21:34 volumio sudo[14699]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:34 volumio volumio[1000]: info: Starting Shairport Sync Jun 23 21:21:35 volumio sudo[14711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 23 21:21:35 volumio sudo[14711]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:21:35 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 23 21:21:35 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 23 21:21:35 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:35 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 23 21:21:35 volumio sudo[14711]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:35 volumio volumio[1000]: info: Shairport-Sync Started Jun 23 21:21:35 volumio mpd[14709]: Jun 23 21:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 23 21:21:35 volumio systemd[1]: Started Music Player Daemon. Jun 23 21:21:35 volumio sudo[14694]: pam_unix(sudo:session): session closed for user root Jun 23 21:21:35 volumio volumio[1000]: error: updateQueue error: null Jun 23 21:21:38 volumio volumio[1000]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jun 23 21:21:38 volumio volumio[1000]: info: CoreStateMachine::getcurrentVolume Jun 23 21:21:38 volumio volumio[1000]: info: CoreCommandRouter::volumioRetrievevolume Jun 23 21:21:38 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:38 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:46 volumio volumio[1000]: info: CoreCommandRouter::volumioVolatilePlay Jun 23 21:21:46 volumio volumio[1000]: info: CoreStateMachine::volatilePlay Jun 23 21:21:46 volumio volumio[1000]: info: Spotify Play Jun 23 21:21:46 volumio volumio[1000]: info: Sending Spotify command to local API: /player/resume Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=debug msg="vorbis: corrupt or missing data in bitstream" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=trace msg="seek to 85089ms (diff: 61ms, samples: 3752424, bytes: 3397991)" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" 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" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=debug msg="resume track at 85028ms" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=trace msg="scheduling prefetch in 103s" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jun 23 21:21:46 volumio go-librespot[6384]: time="2025-06-23T21:21:46Z" level=trace msg="emitting websocket event: playing" Jun 23 21:21:46 volumio volumio[1000]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","play_origin":"playlist"}} Jun 23 21:21:46 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:46 volumio volumio[1000]: SPOTIFY: {"status":"play","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":85001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:46 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:46 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:46 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:21:46 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:47 volumio volumio[1000]: SPOTIFY: PUSH STATE SPOTIFY Jun 23 21:21:47 volumio volumio[1000]: SPOTIFY: {"status":"play","service":"spop","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","seek":85001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jun 23 21:21:47 volumio volumio[1000]: info: CoreCommandRouter::servicePushState Jun 23 21:21:47 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:21:47 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:21:52 volumio go-librespot[6384]: time="2025-06-23T21:21:52Z" level=debug msg="fetched chunk 10/16, size: 524288" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:22:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 21:22:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 21:22:01 volumio volumio[1000]: info: Discovery: Getting this device information Jun 23 21:22:01 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:22:01 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 21:22:05 volumio go-librespot[6384]: time="2025-06-23T21:22:05Z" level=debug msg="fetched chunk 11/16, size: 524288" uri="spotify:track:1fQknzJ5j8dreu0zjTkFpl" Jun 23 21:22:10 volumio go-librespot[6384]: time="2025-06-23T21:22:10Z" level=error msg="output device failed" error="ALSA error at snd_pcm_recover: Input/output error" Jun 23 21:22:10 volumio kernel: usb 1-1.2: USB disconnect, device number 16 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot: pcm_plug.c:71: snd_pcm_plug_close: Assertion `plug->gen.slave == plug->req_slave' failed. Jun 23 21:22:10 volumio go-librespot[6384]: SIGABRT: abort Jun 23 21:22:10 volumio go-librespot[6384]: PC=0xb6ba5fb4 m=8 sigcode=4294967290 Jun 23 21:22:10 volumio go-librespot[6384]: signal arrived during cgo execution Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 69 gp=0x2cf4d88 m=8 mp=0x2c4b708 [syscall]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.cgocall(0x5811e8, 0x2f0ced4) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/cgocall.go:157 +0x50 fp=0x2f0cebc sp=0x2f0cea4 pc=0x1d56c Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output._Cfunc_snd_pcm_close(0xb080cbb0) Jun 23 21:22:10 volumio go-librespot[6384]: _cgo_gotypes.go:406 +0x38 fp=0x2f0ced0 sp=0x2f0cebc pc=0x4976c0 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.(*output).Close.func1(0x2d185b0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:450 +0x50 fp=0x2f0ceec sp=0x2f0ced0 pc=0x49c400 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.(*output).Close(0x2d185b0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:450 +0xdc fp=0x2f0cf1c sp=0x2f0ceec pc=0x49c21c Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.(*Output).Close(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/output.go:96 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/player.(*Player).manageLoop(0x2d2b140) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/player/player.go:221 +0xb2c fp=0x2f0cfe4 sp=0x2f0cf1c pc=0x4a7c58 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/player.NewPlayer.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x28 fp=0x2f0cfec sp=0x2f0cfe4 pc=0x4a7040 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2f0cfec sp=0x2f0cfec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/player.NewPlayer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x1e4 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 1 gp=0x2c02128 m=nil [select, 282 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c54d0c sp=0x2c54cf8 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2c54e0c, 0x2e1ddd8, 0x0, 0x0, 0x2, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c54dac sp=0x2c54d0c pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/zeroconf.(*Zeroconf).Serve(0x2fe4d70, 0x2c54e88) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x228 fp=0x2c54e50 sp=0x2c54dac pc=0x528c80 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).withAppPlayer(0x2c003c0, 0x2e400a0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x2c54ec0 sp=0x2c54e50 pc=0x578aac Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).withCredentials(0x2c003c0, {0x5f3170, 0x2c0e540}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x3d4 fp=0x2c54f1c sp=0x2c54ec0 pc=0x578184 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).SpotifyToken(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131 Jun 23 21:22:10 volumio go-librespot[6384]: main.main() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x530 fp=0x2c54fa8 sp=0x2c54f1c pc=0x57a0cc Jun 23 21:22:10 volumio go-librespot[6384]: runtime.main() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x2c54fec sp=0x2c54fa8 pc=0x5c29c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c54fec sp=0x2c54fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 2 gp=0x2c027e8 m=nil [force gc (idle), 212 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c44fd4 sp=0x2c44fc0 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goparkunlock(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.forcegchelper() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x2c44fec sp=0x2c44fd4 pc=0x5c668 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c44fec sp=0x2c44fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.init.5 in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 3 gp=0x2c02b48 m=nil [GC sweep wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c457c4 sp=0x2c457b0 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goparkunlock(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.bgsweep(0x2c6c000) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x2c457e4 sp=0x2c457c4 pc=0x4506c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcenable.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x2c457ec sp=0x2c457e4 pc=0x3518c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c457ec sp=0x2c457ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcenable in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 18 gp=0x2c84248 m=nil [GC scavenge wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c407b4 sp=0x2c407a0 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goparkunlock(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.(*scavengerState).park(0xaa9020) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2c407c8 sp=0x2c407b4 pc=0x4238c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.bgscavenge(0x2c6c000) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2c407e4 sp=0x2c407c8 pc=0x42ac4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcenable.gowrap2() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x2c407ec sp=0x2c407e4 pc=0x35138 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c407ec sp=0x2c407ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcenable in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 4 gp=0x2c030e8 m=nil [finalizer wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c45f8c sp=0x2c45f78 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.runfinq() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x2c45fec sp=0x2c45f8c pc=0x33f78 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c45fec sp=0x2c45fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.createfing in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 5 gp=0x2c038c8 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683f08, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c445dc sp=0x2c445c8 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683ef8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c445f4 sp=0x2c445dc pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683ef8, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c44608 sp=0x2c445f4 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2c26fb8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c4461c sp=0x2c44608 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Accept(0x2c26fa0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2c44664 sp=0x2c4461c pc=0x10c974 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).accept(0x2c26fa0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x2c446cc sp=0x2c44664 pc=0x2a2504 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPListener).accept(0x2d923a8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x2c446e4 sp=0x2c446cc pc=0x2ba604 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPListener).Accept(0x2d923a8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x2c44700 sp=0x2c446e4 pc=0x2b9670 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*onceCloseListener).Accept(0x2d202a0) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x34 fp=0x2c44718 sp=0x2c44700 pc=0x45e914 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*Server).Serve(0x2d1c0a8, {0x72527c, 0x2d923a8}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x2c447b0 sp=0x2c44718 pc=0x43cfd0 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.Serve(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*ApiServer).serve(0x2d899c0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x2c447e4 sp=0x2c447b0 pc=0x57084c Jun 23 21:22:10 volumio go-librespot[6384]: main.NewApiServer.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x2c447ec sp=0x2c447e4 pc=0x56ff20 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c447ec sp=0x2c447ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by main.NewApiServer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 13 gp=0x2c039e8 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683d28, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cc0e84 sp=0x2cc0e70 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683d18, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2cc0e9c sp=0x2cc0e84 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683d18, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2cc0eb0 sp=0x2cc0e9c pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2fe4e28, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cc0ec4 sp=0x2cc0eb0 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).RawRead(0x2fe4e10, 0x3b0d6e0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2cc0ef0 sp=0x2cc0ec4 pc=0x10d578 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*rawConn).Read(0x3075878, 0x3b0d6e0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2cc0f10 sp=0x2cc0ef0 pc=0x2b4a2c Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2fe1090, 0x3b0d6b0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2cc0f40 sp=0x2cc0f10 pc=0x4c9804 Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2c97d18, {0x2e44000, 0x10000, 0x10000}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x2cc0f9c sp=0x2cc0f40 pc=0x4cd7ec Jun 23 21:22:10 volumio go-librespot[6384]: github.com/grandcat/zeroconf.(*Server).recv4(0x2c94300, 0x2c97d10) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2cc0fe0 sp=0x2cc0f9c pc=0x52407c Jun 23 21:22:10 volumio go-librespot[6384]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2cc0fec sp=0x2cc0fe0 pc=0x523d34 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cc0fec sp=0x2cc0fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 19 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 14 gp=0x2c03b08 m=nil [IO wait, 282 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683c88, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c40e5c sp=0x2c40e48 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683c78, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c40e74 sp=0x2c40e5c pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683c78, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c40e88 sp=0x2c40e74 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2fe4e78, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c40e9c sp=0x2c40e88 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).RawRead(0x2fe4e60, 0x2fefc80) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2c40ec8 sp=0x2c40e9c pc=0x10d578 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*rawConn).Read(0x30758a0, 0x2fefc80) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2c40ee8 sp=0x2c40ec8 pc=0x2b4a2c Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2fe10a0, 0x2fefc50, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2c40f18 sp=0x2c40ee8 pc=0x4c9804 Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Jun 23 21:22:10 volumio go-librespot[6384]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2c97d48, {0x2e2a000, 0x10000, 0x10000}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x2c40f9c sp=0x2c40f18 pc=0x4d1174 Jun 23 21:22:10 volumio go-librespot[6384]: github.com/grandcat/zeroconf.(*Server).recv6(0x2c94300, 0x2c97d40) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2c40fe0 sp=0x2c40f9c pc=0x524270 Jun 23 21:22:10 volumio go-librespot[6384]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2c40fec sp=0x2c40fe0 pc=0x523cd8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c40fec sp=0x2c40fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 19 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 36 gp=0x2e8a008 m=nil [GC worker (idle)]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7f4, 0x2e857b8, 0x1a, 0xa, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c46f90 sp=0x2c46f7c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcBgMarkWorker() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c46fec sp=0x2c46f90 pc=0x37db4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c46fec sp=0x2c46fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 37 gp=0x2e8a248 m=nil [GC worker (idle)]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7f4, 0x2e857d0, 0x1a, 0xa, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e9a790 sp=0x2e9a77c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcBgMarkWorker() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2e9a7ec sp=0x2e9a790 pc=0x37db4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e9a7ec sp=0x2e9a7ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 38 gp=0x2e8a368 m=nil [GC worker (idle)]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7f4, 0x2e857e8, 0x1a, 0xa, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e9af90 sp=0x2e9af7c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcBgMarkWorker() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2e9afec sp=0x2e9af90 pc=0x37db4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e9afec sp=0x2e9afec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 39 gp=0x2e8a488 m=nil [GC worker (idle)]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7f4, 0x2e85800, 0x1a, 0xa, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e9b790 sp=0x2e9b77c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcBgMarkWorker() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2e9b7ec sp=0x2e9b790 pc=0x37db4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e9b7ec sp=0x2e9b7ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by runtime.gcBgMarkStartWorkers in goroutine 35 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 15 gp=0x2c847e8 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683b48, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c55d70 sp=0x2c55d5c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683b38, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c55d88 sp=0x2c55d70 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683b38, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c55d9c sp=0x2c55d88 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2ef8ab8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c55db0 sp=0x2c55d9c pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Read(0x2ef8aa0, {0x313e4bc, 0x3, 0x3}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c55df8 sp=0x2c55db0 pc=0x1092f4 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).Read(0x2ef8aa0, {0x313e4bc, 0x3, 0x3}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2c55e24 sp=0x2c55df8 pc=0x2a0610 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*conn).Read(0x2edc2e8, {0x313e4bc, 0x3, 0x3}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2c55e50 sp=0x2c55e24 pc=0x2b07f0 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPConn).Read(0x2edc2e8, {0x313e4bc, 0x3, 0x3}) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x44 fp=0x2c55e70 sp=0x2c55e50 pc=0x2c3a44 Jun 23 21:22:10 volumio go-librespot[6384]: io.ReadAtLeast({0x722604, 0x2edc2e8}, {0x313e4bc, 0x3, 0x3}, 0x3) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x2c55e9c sp=0x2c55e70 pc=0x1020b0 Jun 23 21:22:10 volumio go-librespot[6384]: io.ReadFull(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/ap.(*shannonConn).receivePacket(0x2d13260) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/shannon.go:80 +0x130 fp=0x2c55efc sp=0x2c55e9c pc=0x48fedc Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/ap.(*Accesspoint).recvLoop(0x2fa40a8) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:251 +0x58 fp=0x2c55fe4 sp=0x2c55efc pc=0x48cda4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x28 fp=0x2c55fec sp=0x2c55fe4 pc=0x48cd20 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c55fec sp=0x2c55fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 71 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x60 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 43 gp=0x2e8a5a8 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2f016a8 sp=0x2f01694 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2f017d4, 0x2f0176c, 0x0, 0x0, 0x2, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2f01748 sp=0x2f016a8 pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/ap.(*Accesspoint).pongAckTicker(0x2fa40a8) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:319 +0x8c fp=0x2f017e4 sp=0x2f01748 pc=0x48d514 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/ap.NewAccesspoint.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x28 fp=0x2f017ec sp=0x2f017e4 pc=0x48b71c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2f017ec sp=0x2f017ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/ap.NewAccesspoint in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x188 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 56 gp=0x2cf4a28 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2f05ea8 sp=0x2f05e94 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2f05fa4, 0x2f05f6c, 0x0, 0x0, 0x5, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2f05f48 sp=0x2f05ea8 pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2dfe8c8) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2f05fe4 sp=0x2f05f48 pc=0x47408c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.newConn.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2f05fec sp=0x2f05fe4 pc=0x473c80 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2f05fec sp=0x2f05fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by nhooyr.io/websocket.newConn in goroutine 89 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 68 gp=0x2cf4c68 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cc3e88 sp=0x2cc3e74 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2cc3fd4, 0x2cc3f50, 0x0, 0x0, 0x2, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2cc3f28 sp=0x2cc3e88 pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/dealer.(*Dealer).pingTicker(0x2d1d728) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:107 +0x8c fp=0x2cc3fe4 sp=0x2cc3f28 pc=0x4b85d0 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/dealer.NewDealer.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x28 fp=0x2cc3fec sp=0x2cc3fe4 pc=0x4b80a0 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cc3fec sp=0x2cc3fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/dealer.NewDealer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x180 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 89 gp=0x2cf4ea8 m=nil [IO wait, 282 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb56838c8, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c578a4 sp=0x2c57890 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb56838b8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c578bc sp=0x2c578a4 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb56838b8, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c578d0 sp=0x2c578bc pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2c26518, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c578e4 sp=0x2c578d0 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Read(0x2c26500, {0x3026000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c5792c sp=0x2c578e4 pc=0x1092f4 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).Read(0x2c26500, {0x3026000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2c57958 sp=0x2c5792c pc=0x2a0610 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*conn).Read(0x2d1a258, {0x3026000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2c57984 sp=0x2c57958 pc=0x2b07f0 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPConn).Read(0x2d1a258, {0x3026000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x44 fp=0x2c579a4 sp=0x2c57984 pc=0x2c3a44 Jun 23 21:22:10 volumio go-librespot[6384]: io.(*multiReader).Read(0x2d28030, {0x3026000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x2c579d0 sp=0x2c579a4 pc=0x103318 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).fill(0x2e9e030) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x2c579f4 sp=0x2c579d0 pc=0x15a4b8 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).ReadByte(0x2e9e030) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x2c57a00 sp=0x2c579f4 pc=0x15ad3c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.readFrameHeader(0x2e9e030, {0x2dfe8f8, 0x8, 0x8}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2c57a40 sp=0x2c57a00 pc=0x4771e0 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2dfe8c8, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2c57b00 sp=0x2c57a40 pc=0x479e44 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).readLoop(0x2dfe8c8, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2c57bc4 sp=0x2c57b00 pc=0x47992c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).reader(0x2dfe8c8, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2c57c50 sp=0x2c57bc4 pc=0x47ab40 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).Read(0x2dfe8c8, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2c57c78 sp=0x2c57c50 pc=0x479210 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*ApiServer).serve.func14({0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x22c fp=0x2c57cd0 sp=0x2c57c78 pc=0x570c88 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.HandlerFunc.ServeHTTP(0x2d1a140, {0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c57ce0 sp=0x2c57cd0 pc=0x4395b4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*ServeMux).ServeHTTP(0x2d10000, {0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2c57d1c sp=0x2c57ce0 pc=0x43b414 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2c57d40 sp=0x2c57d1c pc=0x570a44 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.HandlerFunc.ServeHTTP(0x2d14120, {0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c57d50 sp=0x2c57d40 pc=0x4395b4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.serverHandler.ServeHTTP({0x2d1c0a8}, {0x7253b0, 0x2d1c648}, 0x2d1c008) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2c57d6c sp=0x2c57d50 pc=0x43cba4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*conn).serve(0x2e74120, {0x7256a8, 0x2d22138}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2c57fdc sp=0x2c57d6c pc=0x437df4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*Server).Serve.gowrap3() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2c57fec sp=0x2c57fdc pc=0x43d448 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c57fec sp=0x2c57fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by net/http.(*Server).Serve in goroutine 5 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 71 gp=0x2cf4fc8 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e1985c sp=0x2e19848 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2e19e34, 0x2e19a3c, 0x0, 0x0, 0x6, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2e198fc sp=0x2e1985c pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*AppPlayer).Run(0x2fe4eb0, 0x2e80600) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:430 +0x1fc fp=0x2e19fe0 sp=0x2e198fc pc=0x57df50 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).withAppPlayer.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x30 fp=0x2e19fec sp=0x2e19fe0 pc=0x57944c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e19fec sp=0x2e19fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by main.(*App).withAppPlayer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x348 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 72 gp=0x2cf50e8 m=nil [chan receive]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7c8, 0x2c6c270, 0xe, 0x7, 0x2) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e9df48 sp=0x2e9df34 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.chanrecv(0x2c6c240, 0x2e9dfd0, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x2e9df84 sp=0x2e9df48 pc=0x1fdc4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.chanrecv2(0x2c6c240, 0x2e9dfd0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x2e9df98 sp=0x2e9df84 pc=0x1f908 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).withAppPlayer.func1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x54 fp=0x2e9dfec sp=0x2e9df98 pc=0x57937c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e9dfec sp=0x2e9dfec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by main.(*App).withAppPlayer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 73 gp=0x2cf5328 m=nil [chan receive, 282 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f7c8, 0x2c6c1f0, 0xe, 0x7, 0x2) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c4773c sp=0x2c47728 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.chanrecv(0x2c6c1c0, 0x2c477e0, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x2c47778 sp=0x2c4773c pc=0x1fdc4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.chanrecv2(0x2c6c1c0, 0x2c477e0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x2c4778c sp=0x2c47778 pc=0x1f908 Jun 23 21:22:10 volumio go-librespot[6384]: main.(*App).withAppPlayer.func2() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x2c477ec sp=0x2c4778c pc=0x578f90 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c477ec sp=0x2c477ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by main.(*App).withAppPlayer in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 74 gp=0x2cf5448 m=nil [IO wait, 16 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683dc8, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30b75e8 sp=0x30b75d4 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683db8, 0x72, 0x0) Jun 23 21:22:10 volumio volumio[1000]: info: Jun 23 21:22:10 volumio volumio[1000]: ---------------------------- USB Audio Device Detached Jun 23 21:22:10 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 23 21:22:10 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x30b7600 sp=0x30b75e8 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683db8, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x30b7614 sp=0x30b7600 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2fe4dd8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x30b7628 sp=0x30b7614 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Accept(0x2fe4dc0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x30b7670 sp=0x30b7628 pc=0x10c974 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).accept(0x2fe4dc0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x30b76d8 sp=0x30b7670 pc=0x2a2504 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPListener).accept(0x2d231e8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x30b76f0 sp=0x30b76d8 pc=0x2ba604 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPListener).Accept(0x2d231e8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x30b770c sp=0x30b76f0 pc=0x2b9670 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*onceCloseListener).Accept(0x2cf6aa0) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x34 fp=0x30b7724 sp=0x30b770c pc=0x45e914 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*Server).Serve(0x2d1d908, {0x72527c, 0x2d231e8}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x30b77bc sp=0x30b7724 pc=0x43cfd0 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.Serve(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x30b77ec sp=0x30b77bc pc=0x528d7c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30b77ec sp=0x30b77ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 129 gp=0x30cefc8 m=nil [select, 2 minutes]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cc5c9c sp=0x2cc5c88 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2cc5dfc, 0x2cc5d68, 0x0, 0x0, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2cc5d3c sp=0x2cc5c9c pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/audio.(*KeyProvider).recvLoop(0x2cf07c8) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:52 +0x118 fp=0x2cc5fe4 sp=0x2cc5d3c pc=0x49552c Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x28 fp=0x2cc5fec sp=0x2cc5fe4 pc=0x4960c8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cc5fec sp=0x2cc5fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 71 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/audio/provider.go:42 +0x60 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 77 gp=0x2c03208 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c43ea8 sp=0x2c43e94 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x2c43fa4, 0x2c43f6c, 0x0, 0x0, 0x5, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c43f48 sp=0x2c43ea8 pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2dfeb48) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2c43fe4 sp=0x2c43f48 pc=0x47408c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.newConn.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2c43fec sp=0x2c43fe4 pc=0x473c80 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c43fec sp=0x2c43fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by nhooyr.io/websocket.newConn in goroutine 16 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 78 gp=0x2c03328 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683a08, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e1f938 sp=0x2e1f924 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb56839f8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e1f950 sp=0x2e1f938 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb56839f8, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e1f964 sp=0x2e1f950 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2c26428, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e1f978 sp=0x2e1f964 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Read(0x2c26410, {0x2fc5000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e1f9c0 sp=0x2e1f978 pc=0x1092f4 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).Read(0x2c26410, {0x2fc5000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e1f9ec sp=0x2e1f9c0 pc=0x2a0610 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*conn).Read(0x2edc378, {0x2fc5000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e1fa18 sp=0x2e1f9ec pc=0x2b07f0 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPConn).Read(0x2edc378, {0x2fc5000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x44 fp=0x2e1fa38 sp=0x2e1fa18 pc=0x2c3a44 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*atLeastReader).Read(0x2f481f0, {0x2fc5000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2e1fa64 sp=0x2e1fa38 pc=0x386018 Jun 23 21:22:10 volumio go-librespot[6384]: bytes.(*Buffer).ReadFrom(0x2fbd5cc, {0x723a98, 0x2f481f0}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2e1faa0 sp=0x2e1fa64 pc=0x136d6c Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readFromUntil(0x2fbd448, {0x722604, 0x2edc378}, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2e1fac8 sp=0x2e1faa0 pc=0x386274 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecordOrCCS(0x2fbd448, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2e1fc2c sp=0x2e1fac8 pc=0x383974 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecord(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).Read(0x2fbd448, {0x2c9e000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2e1fc5c sp=0x2e1fc2c pc=0x3897e4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*readWriteCloserBody).Read(0x2f903f0, {0x2c9e000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2429 +0xec fp=0x2e1fc7c sp=0x2e1fc5c pc=0x4538c0 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).fill(0x313a0f0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x2e1fca0 sp=0x2e1fc7c pc=0x15a4b8 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).ReadByte(0x313a0f0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x2e1fcac sp=0x2e1fca0 pc=0x15ad3c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.readFrameHeader(0x313a0f0, {0x2dfeb78, 0x8, 0x8}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2e1fcec sp=0x2e1fcac pc=0x4771e0 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2dfeb48, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2e1fdac sp=0x2e1fcec pc=0x479e44 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).readLoop(0x2dfeb48, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2e1fe70 sp=0x2e1fdac pc=0x47992c Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).reader(0x2dfeb48, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2e1fefc sp=0x2e1fe70 pc=0x47ab40 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).Reader(...) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jun 23 21:22:10 volumio go-librespot[6384]: nhooyr.io/websocket.(*Conn).Read(0x2dfeb48, {0x725688, 0xad3b20}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2e1ff24 sp=0x2e1fefc pc=0x479210 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/dealer.(*Dealer).recvLoop(0x2d1d728) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:153 +0x58 fp=0x2e1ffe4 sp=0x2e1ff24 pc=0x4b8a14 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x28 fp=0x2e1ffec sp=0x2e1ffe4 pc=0x4b9430 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e1ffec sp=0x2e1ffec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/dealer.(*Dealer).reconnect in goroutine 16 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x70 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 187 gp=0x2d04908 m=nil [sync.Cond.Wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f938, 0x2d21e70, 0x14, 0x4, 0x3) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x31e8720 sp=0x31e870c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goparkunlock(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Jun 23 21:22:10 volumio go-librespot[6384]: sync.runtime_notifyListWait(0x2d21e68, 0x26043e) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sema.go:569 +0x1e4 fp=0x31e874c sp=0x31e8720 pc=0x90a94 Jun 23 21:22:10 volumio go-librespot[6384]: sync.(*Cond).Wait(0x2d21e60) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/sync/cond.go:70 +0xbc fp=0x31e8770 sp=0x31e874c pc=0x9cc44 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.(*RingBuffer[...]).PutWait(0x726460, {0x397a000, 0x1ba, 0x1ba}) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/ring_buffer.go:50 +0x8c fp=0x31e8798 sp=0x31e8770 pc=0x49df68 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.(*output).readLoop(0x2d185b0) Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:252 +0xb8 fp=0x31e87d0 sp=0x31e8798 pc=0x49abd4 Jun 23 21:22:10 volumio go-librespot[6384]: go-librespot/output.newOutput.func2() Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:93 +0x20 fp=0x31e87ec sp=0x31e87d0 pc=0x498ec4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x31e87ec sp=0x31e87ec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by go-librespot/output.newOutput in goroutine 69 Jun 23 21:22:10 volumio go-librespot[6384]: /home/runner/work/go-librespot/go-librespot/output/driver_unix.go:92 +0x3d8 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 3256624 gp=0x2e3aa28 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683aa8, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cc1ad4 sp=0x2cc1ac0 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683a98, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2cc1aec sp=0x2cc1ad4 pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683a98, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2cc1b00 sp=0x2cc1aec pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2f23468, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cc1b14 sp=0x2cc1b00 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Read(0x2f23450, {0x2d33000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2cc1b5c sp=0x2cc1b14 pc=0x1092f4 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).Read(0x2f23450, {0x2d33000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2cc1b88 sp=0x2cc1b5c pc=0x2a0610 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*conn).Read(0x385d110, {0x2d33000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2cc1bb4 sp=0x2cc1b88 pc=0x2b07f0 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPConn).Read(0x385d110, {0x2d33000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x44 fp=0x2cc1bd4 sp=0x2cc1bb4 pc=0x2c3a44 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*atLeastReader).Read(0x2d28960, {0x2d33000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2cc1c00 sp=0x2cc1bd4 pc=0x386018 Jun 23 21:22:10 volumio go-librespot[6384]: bytes.(*Buffer).ReadFrom(0x300618c, {0x723a98, 0x2d28960}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2cc1c3c sp=0x2cc1c00 pc=0x136d6c Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readFromUntil(0x3006008, {0x722604, 0x385d110}, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2cc1c64 sp=0x2cc1c3c pc=0x386274 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecordOrCCS(0x3006008, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2cc1dc8 sp=0x2cc1c64 pc=0x383974 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecord(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).Read(0x3006008, {0x3084000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2cc1df8 sp=0x2cc1dc8 pc=0x3897e4 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).Read(0x33e61e0, {0x2e674a4, 0x9, 0x9}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:241 +0x214 fp=0x2cc1e1c sp=0x2cc1df8 pc=0x15ab44 Jun 23 21:22:10 volumio go-librespot[6384]: io.ReadAtLeast({0x7227f8, 0x33e61e0}, {0x2e674a4, 0x9, 0x9}, 0x9) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:335 +0x90 fp=0x2cc1e48 sp=0x2cc1e1c pc=0x1020b0 Jun 23 21:22:10 volumio go-librespot[6384]: io.ReadFull(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/io.go:354 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.http2readFrameHeader({0x2e674a4, 0x9, 0x9}, {0x7227f8, 0x33e61e0}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2cc1e70 sp=0x2cc1e48 pc=0x3f30fc Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*http2Framer).ReadFrame(0x2e67480) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2cc1eec sp=0x2cc1e70 pc=0x3f3894 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*http2clientConnReadLoop).run(0x2cc1fdc) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2cc1fa4 sp=0x2cc1eec pc=0x4180b0 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*http2ClientConn).readLoop(0x2cd0108) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2cc1fe4 sp=0x2cc1fa4 pc=0x4175a4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*http2Transport).newClientConn.gowrap1() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2cc1fec sp=0x2cc1fe4 pc=0x4105f0 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cc1fec sp=0x2cc1fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by net/http.(*http2Transport).newClientConn in goroutine 3256623 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/h2_bundle.go:7879 +0xcb0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 3212900 gp=0x2e5d688 m=nil [IO wait]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f928, 0xb5683be8, 0x2, 0x2, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2cbfafc sp=0x2cbfae8 pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpollblock(0xb5683bd8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2cbfb14 sp=0x2cbfafc pc=0x540f0 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.runtime_pollWait(0xb5683bd8, 0x72) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2cbfb28 sp=0x2cbfb14 pc=0x8eba8 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).wait(0x2f316e8, 0x72, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cbfb3c sp=0x2cbfb28 pc=0x108108 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*pollDesc).waitRead(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Jun 23 21:22:10 volumio go-librespot[6384]: internal/poll.(*FD).Read(0x2f316d0, {0x3196000, 0xa000, 0xa000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2cbfb84 sp=0x2cbfb3c pc=0x1092f4 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*netFD).Read(0x2f316d0, {0x3196000, 0xa000, 0xa000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2cbfbb0 sp=0x2cbfb84 pc=0x2a0610 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*conn).Read(0x2ffb0a0, {0x3196000, 0xa000, 0xa000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2cbfbdc sp=0x2cbfbb0 pc=0x2b07f0 Jun 23 21:22:10 volumio go-librespot[6384]: net.(*TCPConn).Read(0x2ffb0a0, {0x3196000, 0xa000, 0xa000}) Jun 23 21:22:10 volumio go-librespot[6384]: :1 +0x44 fp=0x2cbfbfc sp=0x2cbfbdc pc=0x2c3a44 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*atLeastReader).Read(0x2fe18e0, {0x3196000, 0xa000, 0xa000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:806 +0x78 fp=0x2cbfc28 sp=0x2cbfbfc pc=0x386018 Jun 23 21:22:10 volumio go-librespot[6384]: bytes.(*Buffer).ReadFrom(0x2fbc60c, {0x723a98, 0x2fe18e0}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bytes/buffer.go:211 +0xa4 fp=0x2cbfc64 sp=0x2cbfc28 pc=0x136d6c Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readFromUntil(0x2fbc488, {0x722604, 0x2ffb0a0}, 0x5) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:828 +0xd4 fp=0x2cbfc8c sp=0x2cbfc64 pc=0x386274 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecordOrCCS(0x2fbc488, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:626 +0x134 fp=0x2cbfdf0 sp=0x2cbfc8c pc=0x383974 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).readRecord(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:588 Jun 23 21:22:10 volumio go-librespot[6384]: crypto/tls.(*Conn).Read(0x2fbc488, {0x2d31000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/crypto/tls/conn.go:1370 +0x15c fp=0x2cbfe20 sp=0x2cbfdf0 pc=0x3897e4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*persistConn).Read(0x2ff04d8, {0x2d31000, 0x1000, 0x1000}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1977 +0x16c fp=0x2cbfe54 sp=0x2cbfe20 pc=0x45138c Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).fill(0x3b0cb40) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x2cbfe78 sp=0x2cbfe54 pc=0x15a4b8 Jun 23 21:22:10 volumio go-librespot[6384]: bufio.(*Reader).Peek(0x3b0cb40, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:148 +0x68 fp=0x2cbfe84 sp=0x2cbfe78 pc=0x15a63c Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*persistConn).readLoop(0x2ff04d8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2141 +0x194 fp=0x2cbffe4 sp=0x2cbfe84 pc=0x451f1c Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*Transport).dialConn.gowrap2() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x28 fp=0x2cbffec sp=0x2cbffe4 pc=0x45083c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2cbffec sp=0x2cbffec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by net/http.(*Transport).dialConn in goroutine 3212896 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1799 +0x13f4 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 3212901 gp=0x2faefc8 m=nil [select]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x39f0eb0 sp=0x39f0e9c pc=0x5c82c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.selectgo(0x39f0fac, 0x39f0f78, 0x0, 0x0, 0x2, 0x1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x39f0f50 sp=0x39f0eb0 pc=0x705e4 Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*persistConn).writeLoop(0x2ff04d8) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:2444 +0xd0 fp=0x39f0fe4 sp=0x39f0f50 pc=0x4539dc Jun 23 21:22:10 volumio go-librespot[6384]: net/http.(*Transport).dialConn.gowrap3() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x28 fp=0x39f0fec sp=0x39f0fe4 pc=0x4507e8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit({}) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x39f0fec sp=0x39f0fec pc=0x9346c Jun 23 21:22:10 volumio go-librespot[6384]: created by net/http.(*Transport).dialConn in goroutine 3212896 Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/transport.go:1800 +0x1444 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0xb2cfeb3c Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x8 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0xb6f04968 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0xb2cfeb3c Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xaf Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x47 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xb6e90a3c Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2eba368 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x8 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb2cfeb38 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0xffffffff Jun 23 21:22:10 volumio go-librespot[6384]: pc 0xb6ba5fb4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0x10 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=0 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0xaa9290 m=0 mp=0xaa9b38 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0xaa9c00, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xbefdba64 sp=0xbefdba64 pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0xaa9c00, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xbefdba8c sp=0xbefdba64 pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0xaa9c00) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xbefdbaac sp=0xbefdba8c pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xbefdbac0 sp=0xbefdbaac pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xbefdbb6c sp=0xbefdbac0 pc=0x63138 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xbefdbb88 sp=0xbefdbb6c pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.park_m(0x2d04908) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xbefdbba8 sp=0xbefdbb88 pc=0x64f00 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0x720d40) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xbefdbbb0 sp=0xbefdbba8 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0xaa9c00 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xaab838 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0xaa9290 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x40bb406f Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xbefdba64 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a8 m=2 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2c026c8 m=2 mp=0x2c48588 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0xaab8c4, 0x80, 0x0, 0xb6818d48, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:417 +0x20 fp=0xb6818d28 sp=0xb6818d28 pc=0x944a8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0xaab8c4, 0x0, 0x53d199b9d) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:75 +0x178 fp=0xb6818d50 sp=0xb6818d28 pc=0x551c0 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notetsleep_internal(0xaab8c4, 0x53d199b9d) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:212 +0x124 fp=0xb6818d78 sp=0xb6818d50 pc=0x25418 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notetsleep(0xaab8c4, 0x53d199b9d) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:235 +0x48 fp=0xb6818d8c sp=0xb6818d78 pc=0x255b8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.sysmon() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:6001 +0x2e0 fp=0xb6818de8 sp=0xb6818d8c pc=0x69f84 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart1() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1729 +0x7c fp=0xb6818df8 sp=0xb6818de8 pc=0x5f8c8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart0() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1686 +0x7c fp=0xb6818e0c sp=0xb6818df8 pc=0x5f83c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:210 +0x8 fp=0xb6818e10 sp=0xb6818e0c pc=0x91740 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0xfffffffc Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0xb6818d48 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2c026c8 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x3b9aca00 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb6818d28 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x551c0 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a8 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0x60000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=3 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2c02a28 m=3 mp=0x2c48b08 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0x2c48bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb5efed6c sp=0xb5efed6c pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0x2c48bd0, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb5efed94 sp=0xb5efed6c pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0x2c48bd0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb5efedb4 sp=0xb5efed94 pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb5efedc8 sp=0xb5efedb4 pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.exitsyscall0(0x2fcf568) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4755 +0x26c fp=0xb5efedf0 sp=0xb5efedc8 pc=0x669a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb6accf40) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb5efedf8 sp=0xb5efedf0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x2c48bd0 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x2fcf5b0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x2c48b08 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2c02a28 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb5efed6c Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=4 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2c02d88 m=4 mp=0x2c49088 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0x2c49150, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb54fecc4 sp=0xb54fecc4 pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0x2c49150, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb54fecec sp=0xb54fecc4 pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0x2c49150) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb54fed0c sp=0xb54fecec pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb54fed20 sp=0xb54fed0c pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb54fedcc sp=0xb54fed20 pc=0x63138 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb54fede8 sp=0xb54fedcc pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit0(0x30a1208) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4181 +0x20 fp=0xb54fedf0 sp=0xb54fede8 pc=0x65548 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb6accf40) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb54fedf8 sp=0xb54fedf0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x2c49150 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xaab838 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2c02d88 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x3a13bcf5 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb54fecc4 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=5 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2c84128 m=5 mp=0x2c80008 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0x2c800d0, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb4cfdcac sp=0xb4cfdcac pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0x2c800d0, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb4cfdcd4 sp=0xb4cfdcac pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0x2c800d0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb4cfdcf4 sp=0xb4cfdcd4 pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb4cfdd08 sp=0xb4cfdcf4 pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb4cfddb4 sp=0xb4cfdd08 pc=0x63138 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb4cfddd0 sp=0xb4cfddb4 pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.park_m(0x2e8a488) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xb4cfddf0 sp=0xb4cfddd0 pc=0x64f00 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb5eff900) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb4cfddf8 sp=0xb4cfddf0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x2c800d0 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2c84128 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x44a02bc2 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb4cfdcac Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x1b370 m=6 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2d04128 m=6 mp=0x2d00008 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime/internal/syscall.Syscall6(0x15a, 0x4, 0xb40fe4e8, 0x80, 0x6a0b, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/internal/syscall/asm_linux_arm.s:17 +0x20 fp=0xb40fe46c sp=0xb40fe46c pc=0x1b370 Jun 23 21:22:10 volumio go-librespot[6384]: runtime/internal/syscall.EpollWait(0x4, {0xb40fe4e8, 0x80, 0x80}, 0x80, 0x6a0b) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/internal/syscall/syscall_linux.go:55 +0x60 fp=0xb40fe49c sp=0xb40fe46c pc=0x1b2c8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.netpoll(0x6522477f1) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll_epoll.go:118 +0x17c fp=0xb40fed08 sp=0xb40fe49c pc=0x54ce4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3470 +0xbbc fp=0xb40fedb4 sp=0xb40fed08 pc=0x62ef0 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb40fedd0 sp=0xb40fedb4 pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.park_m(0x2d04908) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xb40fedf0 sp=0xb40fedd0 pc=0x64f00 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb4cfe900) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb40fedf8 sp=0xb40fedf0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0xfffffffc Jun 23 21:22:10 volumio go-librespot[6384]: r1 0xb40fe4e8 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x6a0b Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0x15a Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2d04128 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0xa6b0fc Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x2cecd5f3 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb40fe46c Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x1b2c8 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x1b370 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0x20000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=7 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2c02fc8 m=7 mp=0x2c49608 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0xad429c, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb38fdd94 sp=0xb38fdd94 pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0xad429c, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb38fddbc sp=0xb38fdd94 pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0xad429c) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb38fdddc sp=0xb38fddbc pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.templateThread() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2760 +0x90 fp=0xb38fdde8 sp=0xb38fdddc pc=0x61350 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart1() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1729 +0x7c fp=0xb38fddf8 sp=0xb38fdde8 pc=0x5f8c8 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart0() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1686 +0x7c fp=0xb38fde0c sp=0xb38fddf8 pc=0x5f83c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mstart() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:210 +0x8 fp=0xb38fde10 sp=0xb38fde0c pc=0x91740 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0xad429c Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0xbefdb9da Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xb38fe440 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2c02fc8 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0xb2d00470 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb38fdd94 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=9 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x2e3b9e8 m=9 mp=0x2c80588 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0x2c80650, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb2042cc4 sp=0xb2042cc4 pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0x2c80650, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb2042cec sp=0xb2042cc4 pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0x2c80650) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb2042d0c sp=0xb2042cec pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb2042d20 sp=0xb2042d0c pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3512 +0xe04 fp=0xb2042dcc sp=0xb2042d20 pc=0x63138 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb2042de8 sp=0xb2042dcc pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.goexit0(0x3139688) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4181 +0x20 fp=0xb2042df0 sp=0xb2042de8 pc=0x65548 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb6accf40) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb2042df8 sp=0xb2042df0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x2c80650 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x2c10054 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xaab838 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x2e3b9e8 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0xe6bd8bbc Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb2042cc4 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: ----- Jun 23 21:22:10 volumio go-librespot[6384]: SIGQUIT: quit Jun 23 21:22:10 volumio go-librespot[6384]: PC=0x944a4 m=10 sigcode=0 Jun 23 21:22:10 volumio go-librespot[6384]: goroutine 0 gp=0x39e8128 m=10 mp=0x2c80b08 [idle]: Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futex(0x2c80bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sys_linux_arm.s:416 +0x1c fp=0xb1841c94 sp=0xb1841c94 pc=0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.futexsleep(0x2c80bd0, 0x0, 0xffffffffffffffff) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/os_linux.go:69 +0x5c fp=0xb1841cbc sp=0xb1841c94 pc=0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.notesleep(0x2c80bd0) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/lock_futex.go:170 +0xb8 fp=0xb1841cdc sp=0xb1841cbc pc=0x25294 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mPark(...) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:1761 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.stopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:2782 +0x90 fp=0xb1841cf0 sp=0xb1841cdc pc=0x61498 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.gcstopm() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3081 +0xd4 fp=0xb1841d08 sp=0xb1841cf0 pc=0x62150 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.findRunnable() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3149 +0x48 fp=0xb1841db4 sp=0xb1841d08 pc=0x6237c Jun 23 21:22:10 volumio go-librespot[6384]: runtime.schedule() Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:3868 +0xac fp=0xb1841dd0 sp=0xb1841db4 pc=0x64688 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.park_m(0x2cf4d88) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:4036 +0x1b0 fp=0xb1841df0 sp=0xb1841dd0 pc=0x64f00 Jun 23 21:22:10 volumio go-librespot[6384]: runtime.mcall(0xb6819900) Jun 23 21:22:10 volumio go-librespot[6384]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:265 +0x48 fp=0xb1841df8 sp=0xb1841df0 pc=0x917a0 Jun 23 21:22:10 volumio go-librespot[6384]: trap 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: error 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: oldmask 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r0 0x2c80bd0 Jun 23 21:22:10 volumio go-librespot[6384]: r1 0x80 Jun 23 21:22:10 volumio go-librespot[6384]: r2 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r3 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r4 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r5 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r6 0x0 Jun 23 21:22:10 volumio go-librespot[6384]: r7 0xf0 Jun 23 21:22:10 volumio go-librespot[6384]: r8 0x1 Jun 23 21:22:10 volumio go-librespot[6384]: r9 0xaab838 Jun 23 21:22:10 volumio go-librespot[6384]: r10 0x39e8128 Jun 23 21:22:10 volumio go-librespot[6384]: fp 0x6 Jun 23 21:22:10 volumio go-librespot[6384]: ip 0x40bdc983 Jun 23 21:22:10 volumio go-librespot[6384]: sp 0xb1841c94 Jun 23 21:22:10 volumio go-librespot[6384]: lr 0x550a4 Jun 23 21:22:10 volumio go-librespot[6384]: pc 0x944a4 Jun 23 21:22:10 volumio go-librespot[6384]: cpsr 0xa0000010 Jun 23 21:22:10 volumio go-librespot[6384]: fault 0x0 Jun 23 21:22:10 volumio kernel: usb 1-1.2: new high-speed USB device number 17 using xhci_hcd Jun 23 21:22:10 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.03 Jun 23 21:22:10 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 23 21:22:10 volumio kernel: usb 1-1.2: Product: D50s Jun 23 21:22:10 volumio kernel: usb 1-1.2: Manufacturer: Topping Jun 23 21:22:10 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jun 23 21:22:10 volumio volumio[1000]: info: Jun 23 21:22:10 volumio volumio[1000]: ---------------------------- USB Audio Device Attached Jun 23 21:22:10 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 23 21:22:10 volumio go-librespot[6384]: Aborted Jun 23 21:22:10 volumio volumio[1000]: info: Connection to go-librespot Websocket closed Jun 23 21:22:10 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jun 23 21:22:10 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jun 23 21:22:13 volumio volumio[1000]: info: Initializing connection to go-librespot Websocket Jun 23 21:22:13 volumio volumio[1000]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jun 23 21:22:13 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jun 23 21:22:13 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jun 23 21:22:13 volumio systemd[1]: Stopped go-librespot Daemon. Jun 23 21:22:14 volumio systemd[1]: Started go-librespot Daemon. Jun 23 21:22:14 volumio go-librespot[14838]: Librespot-go daemon starting... Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=info msg="generated new device id: 57d9560ced3753515183eb0b522cf5f035ad1599" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="stored credentials found for 11130418669" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" 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]" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" 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]" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" 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]" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="zeroconf server listening on port 45037" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="obtained new client token: AAB2o4I4p2412dDKAlRUHHnAT8rRSYVTUfAfKDUqYDyyxucvgObr3V0LnBbEdHNhGR+Hov3/8TFcpIqUjyolx/IljA2pLjgHjz0g1iEno0uggmpgAjjeONDRXcTLqhMRLClR8yMRXmC5vMfFWn9UnrBiEEwPT7qrkX2HpoQcxrD9sZsgIwee0/GyOlHjjSmQNGS5EjFcpL6QuLR4MO9FH6b9I9kDvYBQaR9C7Aup5j55dFxs3lICyQL8Zrjm" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=info msg="connected to ap-gew4.spotify.com:4070" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="completed keyexchange" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="completed challenge" Jun 23 21:22:14 volumio go-librespot[14838]: time="2025-06-23T21:22:14Z" level=debug msg="authenticated as 11130418669" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="authenticated as 11130418669" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="dealer connection opened" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="initializing zeroconf session, username: 11130418669" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="autoplay enabled: false" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="received connection id: MjFlMDAxZTMtY2UxNC00MDRjLTk5NWMtMjA4MjNiZWZlYTE0K2RlYWxlcit0Y3A6Ly8wYWNhNTg2MC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArNzQ5MEI2MkQ2NTBEQUJGOTc1RjMyMDEyRDk4RTYwNTMzNjgyMzI2MUNENEQ1MjBBRUQwQUY2ODBGQzY4Mzk0Qw==" Jun 23 21:22:15 volumio go-librespot[14838]: time="2025-06-23T21:22:15Z" level=debug msg="put connect state because NEW_DEVICE" Jun 23 21:22:16 volumio volumio[1000]: info: Initializing connection to go-librespot Websocket Jun 23 21:22:16 volumio go-librespot[14838]: time="2025-06-23T21:22:16Z" level=debug msg="new websocket client" Jun 23 21:22:16 volumio volumio[1000]: info: Connection to go-librespot Websocket established Jun 23 21:22:19 volumio volumio[1000]: info: Getting Spotify volume Jun 23 21:22:19 volumio volumio[1000]: info: Spotify volume: 100 Jun 23 21:22:19 volumio volumio[1000]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jun 23 21:22:19 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:22:28 volumio kernel: usb 1-1.2: USB disconnect, device number 17 Jun 23 21:22:28 volumio volumio[1000]: info: Jun 23 21:22:28 volumio volumio[1000]: ---------------------------- USB Audio Device Detached Jun 23 21:22:28 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioDetach Jun 23 21:22:28 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:22:28 volumio kernel: usb 1-1.2: new high-speed USB device number 18 using xhci_hcd Jun 23 21:22:28 volumio kernel: usb 1-1.2: New USB device found, idVendor=152a, idProduct=8750, bcdDevice= 1.03 Jun 23 21:22:29 volumio kernel: usb 1-1.2: New USB device strings: Mfr=1, Product=3, SerialNumber=0 Jun 23 21:22:29 volumio kernel: usb 1-1.2: Product: D50s Jun 23 21:22:29 volumio kernel: usb 1-1.2: Manufacturer: Topping Jun 23 21:22:29 volumio kernel: usb 1-1.2: 1:3 : unsupported format bits 0x100000000 Jun 23 21:22:29 volumio volumio[1000]: info: Jun 23 21:22:29 volumio volumio[1000]: ---------------------------- USB Audio Device Attached Jun 23 21:22:29 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , usbAudioAttach Jun 23 21:22:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 23 21:22:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 23 21:22:31 volumio volumio[1000]: info: Discovery: Getting this device information Jun 23 21:22:31 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:22:31 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 23 21:22:34 volumio volumio[1000]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Jun 23 21:22:34 volumio volumio[1000]: info: Preparing to save Alsa Options, stopping services first Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::volumioGetState Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPause Jun 23 21:22:34 volumio volumio[1000]: info: CoreStateMachine::pause Jun 23 21:22:34 volumio volumio[1000]: info: CoreStateMachine::stPlaybackTimer Jun 23 21:22:34 volumio volumio[1000]: info: CoreStateMachine::servicePause Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::servicePause Jun 23 21:22:34 volumio volumio[1000]: info: Spotify Received pause Jun 23 21:22:34 volumio volumio[1000]: SPOTIFY: SPOTIFY PAUSE Jun 23 21:22:34 volumio volumio[1000]: SPOTIFY: {"status":"play","title":"Not Your Man","artist":"Teddy Swims","album":"I've Tried Everything But Therapy (Part 2)","albumart":"https://i.scdn.co/image/ab67616d00001e0270b88fc5a2e13bc5440d947c","uri":"spotify:track:1fQknzJ5j8dreu0zjTkFpl","trackType":"spotify","codec":"ogg","seek":118001,"duration":217,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":null,"repeat":null,"repeatSingle":null,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"service":"spop"} Jun 23 21:22:34 volumio volumio[1000]: info: Sending Spotify command to local API: /player/pause Jun 23 21:22:34 volumio volumio[1000]: info: Saving Audio Output to: {"output_device":{"value":"2","label":"snd_allo_digione"},"i2s":true,"i2sid":{"value":"allo-digione","label":"Allo DigiOne"}} Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: Enabling I2S DAC: Allo DigiOne Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio sudo[14892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Jun 23 21:22:34 volumio sudo[14892]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio sudo[14892]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:34 volumio volumio[1000]: info: No Overlays Loaded Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio sudo[14896]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay allo-digione Jun 23 21:22:34 volumio sudo[14896]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/status Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/#sound-dai-cells Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/compatible Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/reg Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/PVDD-supply Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/DVDD-supply Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/status Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2c@7e804000/wm8804@3b/wlf,reset-gpio Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock44-gpio Jun 23 21:22:34 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/clock48-gpio Jun 23 21:22:34 volumio sudo[14896]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: Device 2 does not have any Mixer Control Available Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jun 23 21:22:34 volumio volumio[1000]: info: Updating Volume Controller Parameters: Device: 2 Name: Allo DigiOne Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Jun 23 21:22:34 volumio volumio[1000]: info: Disabling external Volume Control Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Jun 23 21:22:34 volumio volumio[1000]: info: CoreStateMachine::pushState Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::volumioPushState Jun 23 21:22:34 volumio volumio[1000]: info: Preparing to generate the ALSA configuration file Jun 23 21:22:34 volumio volumio[1000]: info: I2S Param [object Object] successfully enabled Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 23 21:22:34 volumio volumio[1000]: info: Getting Alsa Cards List without I2S DAC Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 23 21:22:34 volumio volumio[1000]: info: Asound.conf file unchanged, so no further update is needed Jun 23 21:22:34 volumio volumio[1000]: info: Output device has changed, restarting MPD Jun 23 21:22:34 volumio sudo[14922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 23 21:22:34 volumio sudo[14922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio sudo[14922]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:34 volumio volumio[1000]: info: Output device has changed, restarting Shairport Sync Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio sudo[14925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 23 21:22:34 volumio sudo[14925]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio systemd[1]: Stopping Music Player Daemon... Jun 23 21:22:34 volumio volumio[1000]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 23 21:22:34 volumio systemd[1]: mpd.service: Succeeded. Jun 23 21:22:34 volumio systemd[1]: Stopped Music Player Daemon. Jun 23 21:22:34 volumio volumio[1000]: info: MPD Permissions set Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 23 21:22:34 volumio volumio[1000]: info: Starting Shairport Sync Jun 23 21:22:34 volumio sudo[14936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jun 23 21:22:34 volumio sudo[14936]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio systemd[1]: Starting Music Player Daemon... Jun 23 21:22:34 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jun 23 21:22:34 volumio systemd[1]: shairport-sync.service: Succeeded. Jun 23 21:22:34 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jun 23 21:22:34 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jun 23 21:22:34 volumio sudo[14936]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:34 volumio volumio[1000]: info: Shairport-Sync Started Jun 23 21:22:34 volumio sudo[14930]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 23 21:22:34 volumio sudo[14930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 23 21:22:34 volumio sudo[14930]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:35 volumio mpd[14942]: Jun 23 21:22 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 23 21:22:35 volumio systemd[1]: Started Music Player Daemon. Jun 23 21:22:35 volumio sudo[14925]: pam_unix(sudo:session): session closed for user root Jun 23 21:22:35 volumio volumio[1000]: error: updateQueue error: null Jun 23 21:22:35 volumio volumio[1000]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Jun 23 21:22:35 volumio volumio[1000]: info: PLUGIN onReboot : networkfs Jun 23 21:22:35 volumio volumio[1000]: info: PLUGIN onReboot : audiophonicsonoff Jun 23 21:22:35 volumio volumio[1000]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 21:22:35 volumio volumio[1000]: TypeError: Cannot read property 'writeSync' of undefined Jun 23 21:22:35 volumio volumio[1000]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_controller/audiophonicsonoff/index.js:35:25) Jun 23 21:22:35 volumio volumio[1000]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Jun 23 21:22:35 volumio volumio[1000]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Jun 23 21:22:35 volumio volumio[1000]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Jun 23 21:22:35 volumio volumio[1000]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Jun 23 21:22:35 volumio volumio[1000]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Jun 23 21:22:35 volumio volumio[1000]: at CoreCommandRouter.reboot (/volumio/app/index.js:1344:22) Jun 23 21:22:35 volumio volumio[1000]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:864:33) Jun 23 21:22:35 volumio volumio[1000]: at Socket.emit (events.js:315:20) Jun 23 21:22:35 volumio volumio[1000]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jun 23 21:22:35 volumio volumio[1000]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jun 23 21:22:35 volumio volumio[1000]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 23 21:22:35 volumio sudo[14954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-23 21:21 Jun 23 21:22:35 volumio sudo[14954]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"