Dec 16 12:26:00 living-room volumio[1173]: info: CoreCommandRouter::volumioGetQueue Dec 16 12:26:00 living-room volumio[1173]: info: CoreStateMachine::getQueue Dec 16 12:26:00 living-room volumio[1173]: info: CorePlayQueue::getQueue Dec 16 12:26:01 living-room go-librespot[3891]: time="2025-12-16T12:26:01+07:00" level=trace msg="sent dealer ping" Dec 16 12:26:01 living-room go-librespot[3891]: time="2025-12-16T12:26:01+07:00" level=trace msg="received dealer pong" Dec 16 12:26:02 living-room volumio[1173]: info: CoreCommandRouter::volumioClearQueue Dec 16 12:26:02 living-room volumio[1173]: info: CoreStateMachine::ClearQueue Dec 16 12:26:02 living-room volumio[1173]: info: CoreStateMachine::stop Dec 16 12:26:02 living-room volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 12:26:02 living-room volumio[1173]: info: CorePlayQueue::clearPlayQueue Dec 16 12:26:02 living-room volumio[1173]: info: CorePlayQueue::saveQueue Dec 16 12:26:02 living-room volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 16 12:26:02 living-room volumio[1173]: info: CoreCommandRouter::volumioPushQueue Dec 16 12:26:06 living-room volumio[1173]: verbose: New Socket.io Connection to 192.168.1.36:3000 from 192.168.1.37 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 16 12:26:06 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 12:26:06 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 12:26:06 living-room volumio[1173]: info: Discovery: Getting this device information Dec 16 12:26:06 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:06 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:06 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 12:26:07 living-room volumio[1173]: verbose: New Socket.io Connection to 192.168.1.36 from 192.168.1.37 UA: Mozilla/5.0 (Linux; Android 15; BVL-N49 Build/HONORBVL-N49; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetVisibleSources Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:07 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 16 12:26:07 living-room volumio[1173]: info: Received Get System Info Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 16 12:26:07 living-room volumio[1173]: info: Discovery: Getting this device information Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:07 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:07 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:07 living-room volumio[1173]: info: Listing playlists Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetQueue Dec 16 12:26:07 living-room volumio[1173]: info: CoreStateMachine::getQueue Dec 16 12:26:07 living-room volumio[1173]: info: CorePlayQueue::getQueue Dec 16 12:26:07 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 16 12:26:09 living-room volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 12:26:09 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 16 12:26:09 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 16 12:26:09 living-room sudo[4158]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 16 12:26:09 living-room sudo[4158]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:09 living-room sudo[4163]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 12:26:09 living-room sudo[4163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:10 living-room sudo[4158]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:10 living-room sudo[4163]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:10 living-room sudo[4176]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 12:26:10 living-room sudo[4176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:10 living-room sudo[4169]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 16 12:26:10 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Dec 16 12:26:10 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Dec 16 12:26:10 living-room sudo[4169]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:10 living-room sudo[4176]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:10 living-room sudo[4169]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:10 living-room sudo[4179]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 16 12:26:10 living-room sudo[4179]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:10 living-room sudo[4184]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 16 12:26:10 living-room sudo[4184]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:10 living-room sudo[4179]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:10 living-room sudo[4184]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:13 living-room go-librespot[3891]: time="2025-12-16T12:26:13+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 404" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="handling transfer player command from 95706c2031f3b07e73cd78d0a05e4be890f48008" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1E35lrqlHGKfTk" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1E35lrqlHGKfTk" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="loading track (paused: false, position: 13212ms)" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=trace msg="emitting websocket event: will_play" Dec 16 12:26:14 living-room volumio[1173]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1E35lrqlHGKfTk","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","play_origin":"playlist"}} Dec 16 12:26:14 living-room volumio[1173]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1E35lrqlHGKfTk","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","play_origin":"playlist"}} Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="selected format OGG_VORBIS_320 (69d88cd0335b349a0bb0dfe318a0870d81b9630f)" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="requested aes key for file 69d88cd0335b349a0bb0dfe318a0870d81b9630f, gid: 2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1380" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:14 living-room go-librespot[3891]: time="2025-12-16T12:26:14+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1136" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="fetched first chunk of 14, total size is 7205917 bytes" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=trace msg="seek to 13212ms (diff: 127ms, samples: 582649, bytes: 500705)" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="created new output device" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=info msg="loaded track \"Happy Together\" (paused: false, position: 13212ms, duration: 176917ms, prefetched: false)" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="fetched chunk 4/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=trace msg="scheduling prefetch in 134s" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=trace msg="emitting websocket event: metadata" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=trace msg="emitting websocket event: active" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="sending successful reply for dealer request" Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","name":"Happy Together","artist_names":["Christy Baron"],"album_name":"Take This Journey","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","position":13212,"duration":176917,"release_date":"year:2002 month:10 day:22","track_number":1,"disc_number":1}} Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"active","data":null} Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Dec 16 12:26:15 living-room volumio[1173]: info: Aligning Spotify Volume to Volumio Volume Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:15 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","name":"Happy Together","artist_names":["Christy Baron"],"album_name":"Take This Journey","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","position":13212,"duration":176917,"release_date":"year:2002 month:10 day:22","track_number":1,"disc_number":1}} Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"active","data":null} Dec 16 12:26:15 living-room volumio[1173]: info: Aligning Spotify Volume to Volumio Volume Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:26:15 living-room volumio[1173]: info: CorePlayQueue::getTrack 0 Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 16 12:26:15 living-room go-librespot[3891]: time="2025-12-16T12:26:15+07:00" level=trace msg="emitting websocket event: playing" Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1E35lrqlHGKfTk","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","resume":false,"play_origin":"playlist"}} Dec 16 12:26:15 living-room volumio[1173]: info: Spotify is playing in volatile mode Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: UNSET VOLATILE Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Happy Together","artist":"Christy Baron","album":"Take This Journey","albumart":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","trackType":"spotify","seek":13212,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::servicePushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::pushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1E35lrqlHGKfTk","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","resume":false,"play_origin":"playlist"}} Dec 16 12:26:15 living-room volumio[1173]: info: Spotify is playing in volatile mode Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: UNSET VOLATILE Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"stop","position":0,"title":"","artist":"","album":"","albumart":"/albumart","duration":0,"uri":"","seek":0,"samplerate":"","channels":"","bitdepth":"","Streaming":false,"service":"mpd","volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"random":null,"repeat":null,"repeatSingle":false,"updatedb":false,"consume":false} Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Happy Together","artist":"Christy Baron","album":"Take This Journey","albumart":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","trackType":"spotify","seek":13212,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::servicePushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::pushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Happy Together","artist":"Christy Baron","album":"Take This Journey","albumart":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","trackType":"spotify","seek":13212,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::servicePushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::pushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: PUSH STATE SPOTIFY Dec 16 12:26:15 living-room volumio[1173]: SPOTIFY: {"status":"play","service":"spop","title":"Happy Together","artist":"Christy Baron","album":"Take This Journey","albumart":"https://i.scdn.co/image/ab67616d00001e0270256d462f492b650425cc0c","uri":"spotify:track:2ZsccPriJVj2LVpW0eUgXt","trackType":"spotify","seek":13212,"duration":176,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::servicePushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreStateMachine::pushState Dec 16 12:26:15 living-room volumio[1173]: info: CoreCommandRouter::volumioPushState Dec 16 12:26:17 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 16 12:26:17 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 16 12:26:19 living-room volumio[1173]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 16 12:26:22 living-room ntpd[987]: CLOCK: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Dec 16 12:26:27 living-room go-librespot[3891]: time="2025-12-16T12:26:27+07:00" level=debug msg="fetched chunk 5/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:31 living-room go-librespot[3891]: time="2025-12-16T12:26:31+07:00" level=trace msg="sent dealer ping" Dec 16 12:26:31 living-room go-librespot[3891]: time="2025-12-16T12:26:31+07:00" level=trace msg="received dealer pong" Dec 16 12:26:40 living-room go-librespot[3891]: time="2025-12-16T12:26:40+07:00" level=debug msg="fetched chunk 6/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:53 living-room go-librespot[3891]: time="2025-12-16T12:26:53+07:00" level=debug msg="fetched chunk 7/13, size: 524288" uri="spotify:track:2ZsccPriJVj2LVpW0eUgXt" Dec 16 12:26:55 living-room volumio[1173]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 16 12:26:55 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 16 12:26:55 living-room volumio[1173]: info: Creating Spotify config file Dec 16 12:26:55 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 12:26:55 living-room volumio[1173]: info: Spotify config file written Dec 16 12:26:55 living-room sudo[4271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 16 12:26:55 living-room sudo[4271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:26:55 living-room systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 16 12:26:55 living-room systemd[1]: go-librespot-daemon.service: Killing process 3895 (go-librespot) with signal SIGKILL. Dec 16 12:26:55 living-room systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 16 12:26:55 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 16 12:26:55 living-room systemd[1]: go-librespot-daemon.service: Consumed 1.759s CPU time. Dec 16 12:26:55 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:26:55 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:26:56 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 16 12:26:56 living-room go-librespot[4273]: go-librespot daemon starting... Dec 16 12:26:56 living-room sudo[4271]: pam_unix(sudo:session): session closed for user root Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=info msg="running go-librespot 0.4.0" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="app state loaded" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=info msg="zeroconf server listening on port 35889" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="obtained new client token: AACZQPS98CytHQ8I9I86rkBxcS1Rjqym8xQhU7WVSoPfVUTqNKH4AVUJXXnPf2Q8sxSmLJ9kNiIr6sz1+F7t7p43c2O720Ev4Hq304CwTCxrIOS4YSTHFCTrYFclUtXPtjt37x8Hjme5nQhsADaKNpli4M5ex2ftSsKJHXy5C+QIEpLuxa+vt8ixbwPkg4F8dhHS26UPmGWMTR5wJ2KmgBnLP8C5GzazvR5fHpEhe+I74QXoKHPxRSqZ" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="completed keyexchange" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="completed challenge" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=info msg="authenticated AP" username="31************************v4" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=info msg="authenticated Login5" username="31************************v4" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="initializing zeroconf session" username="31************************v4" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="dealer connection opened" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=trace msg="starting accesspoint recv loop" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=trace msg="starting dealer recv loop" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=trace msg="received accesspoint ping" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=debug msg="received connection id: ZmM0YjNjMjktYjc3...REUxQzU4Nzg2QQ==" Dec 16 12:26:56 living-room go-librespot[4274]: time="2025-12-16T12:26:56+07:00" level=trace msg="received accesspoint pong ack" Dec 16 12:26:57 living-room go-librespot[4274]: time="2025-12-16T12:26:57+07:00" level=debug msg="put connect state because NEW_DEVICE" Dec 16 12:26:57 living-room go-librespot[4274]: time="2025-12-16T12:26:57+07:00" level=debug msg="update volume requested to 65535/65535" Dec 16 12:26:57 living-room go-librespot[4274]: time="2025-12-16T12:26:57+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 16 12:26:57 living-room go-librespot[4274]: time="2025-12-16T12:26:57+07:00" level=trace msg="emitting websocket event: volume" Dec 16 12:26:58 living-room volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 16 12:26:58 living-room go-librespot[4274]: time="2025-12-16T12:26:58+07:00" level=debug msg="new websocket client" Dec 16 12:26:58 living-room volumio[1173]: info: Connection to go-librespot Websocket established Dec 16 12:26:59 living-room volumio[1173]: info: go-librespot daemon successfully initialized Dec 16 12:27:01 living-room volumio[1173]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Dec 16 12:27:01 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Dec 16 12:27:01 living-room volumio[1173]: info: Creating Spotify config file Dec 16 12:27:01 living-room volumio[1173]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 16 12:27:01 living-room volumio[1173]: info: Spotify config file written Dec 16 12:27:01 living-room sudo[4282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Dec 16 12:27:01 living-room sudo[4282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 16 12:27:01 living-room systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Dec 16 12:27:01 living-room systemd[1]: go-librespot-daemon.service: Killing process 4278 (go-librespot) with signal SIGKILL. Dec 16 12:27:01 living-room systemd[1]: go-librespot-daemon.service: Deactivated successfully. Dec 16 12:27:01 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:27:01 living-room systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 16 12:27:01 living-room systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 16 12:27:01 living-room go-librespot[4284]: go-librespot daemon starting... Dec 16 12:27:01 living-room sudo[4282]: pam_unix(sudo:session): session closed for user root Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=info msg="running go-librespot 0.4.0" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="app state loaded" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=info msg="zeroconf server listening on port 39115" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="obtained new client token: AAD39CZZK/oNiqi6DtT/lPfbVqBu1RFsU8Mv87IarQmDHEOvHEMqRsYABTebzlHMRbdxtVU7qdGECM062JB5r19X+MaS3yqK9lLi3/jXV4nCArnstzOK/1n3mRPzH/jFjALz0roMPFdpO3BK4DcTHJgBgif89Fmee21SbCoUa9n8gFsU3qmglg+kJz02b0mRfWVkcJmspR/wlN9gswdN5rlFdOiXIs2o0DQGzUAtExY+OStwZ7fQqgb/" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="completed keyexchange" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="completed challenge" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=info msg="authenticated AP" username="31************************v4" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=info msg="authenticated Login5" username="31************************v4" Dec 16 12:27:01 living-room go-librespot[4285]: time="2025-12-16T12:27:01+07:00" level=debug msg="initializing zeroconf session" username="31************************v4" Dec 16 12:27:01 living-room volumio[1173]: info: Getting Spotify volume Dec 16 12:27:01 living-room volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 16 12:27:02 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:27:02 living-room volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="new websocket client" Dec 16 12:27:02 living-room volumio[1173]: info: Connection to go-librespot Websocket established Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="dealer connection opened" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=trace msg="starting accesspoint recv loop" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=trace msg="starting dealer recv loop" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=trace msg="received accesspoint ping" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 16 12:27:02 living-room volumio[1173]: info: Spotify volume: 100 Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="received connection id: M2MwMGVhMzItZDgy...OTgwRDBEOTg0Mg==" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=trace msg="received accesspoint pong ack" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="put connect state because NEW_DEVICE" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="update volume requested to 65535/65535" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=debug msg="put connect state because VOLUME_CHANGED" Dec 16 12:27:02 living-room go-librespot[4285]: time="2025-12-16T12:27:02+07:00" level=trace msg="emitting websocket event: volume" Dec 16 12:27:02 living-room volumio[1173]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Dec 16 12:27:02 living-room volumio[1173]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Dec 16 12:27:04 living-room volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 16 12:27:04 living-room go-librespot[4285]: time="2025-12-16T12:27:04+07:00" level=debug msg="new websocket client" Dec 16 12:27:04 living-room volumio[1173]: info: Connection to go-librespot Websocket established Dec 16 12:27:04 living-room volumio[1173]: info: go-librespot daemon successfully initialized Dec 16 12:27:05 living-room volumio[1173]: info: Getting Spotify volume Dec 16 12:27:05 living-room volumio[1173]: info: Spotify volume: 100 Dec 16 12:27:05 living-room volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 16 12:27:05 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:27:07 living-room volumio[1173]: info: Getting Spotify volume Dec 16 12:27:07 living-room volumio[1173]: info: Spotify volume: 100 Dec 16 12:27:07 living-room volumio[1173]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 16 12:27:07 living-room volumio[1173]: info: CoreCommandRouter::volumioGetState Dec 16 12:27:07 living-room volumio[1173]: info: Initializing connection to go-librespot Websocket Dec 16 12:27:07 living-room go-librespot[4285]: time="2025-12-16T12:27:07+07:00" level=debug msg="new websocket client" Dec 16 12:27:07 living-room volumio[1173]: info: Connection to go-librespot Websocket established Dec 16 12:27:09 living-room volumio[1173]: info: CoreCommandRouter::volumioNext Dec 16 12:27:09 living-room volumio[1173]: info: CoreStateMachine::next Dec 16 12:27:09 living-room volumio[1173]: info: Spotify next Dec 16 12:27:09 living-room volumio[1173]: info: Sending Spotify command to local API: /player/next Dec 16 12:27:09 living-room go-librespot[4285]: panic: runtime error: invalid memory address or nil pointer dereference Dec 16 12:27:09 living-room go-librespot[4285]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x4 pc=0x6372e0] Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 72 gp=0x2aa87e8 m=0 mp=0xce0d20 [running]: Dec 16 12:27:09 living-room go-librespot[4285]: panic({0x6cfb00, 0xca02c0}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x2a01398 sp=0x2a01344 pc=0x58bf8 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.panicmem(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/panic.go:261 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.sigpanic() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x2a013c8 sp=0x2a01398 pc=0x74be4 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*AppPlayer).loadCurrentTrack(0x2ce0000, {0x8874f0, 0xd0ad00}, 0x1, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/controls.go:255 +0x144 fp=0x2a015f8 sp=0x2a013cc pc=0x6372e0 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*AppPlayer).advanceNext(0x2ce0000, {0x8874f0, 0xd0ad00}, 0x1, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/controls.go:634 +0x448 fp=0x2a016b8 sp=0x2a015f8 pc=0x63af6c Dec 16 12:27:09 living-room go-librespot[4285]: main.(*AppPlayer).skipNext(0x2ce0000, {0x8874f0, 0xd0ad00}, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/controls.go:545 +0x4dc fp=0x2a01754 sp=0x2a016b8 pc=0x63a978 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*AppPlayer).handleApiRequest(0x2ce0000, {0x8874f0, 0xd0ad00}, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x2ba3240}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/player.go:478 +0x12c fp=0x2a018e4 sp=0x2a01754 pc=0x643810 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*AppPlayer).Run(0x2ce0000, {0x8874f0, 0xd0ad00}, 0x2a76b80) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/player.go:610 +0x864 fp=0x2a01fd8 sp=0x2a018e4 pc=0x645db0 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).withAppPlayer.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:258 +0x40 fp=0x2a01fec sp=0x2a01fd8 pc=0x63e874 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2a01fec sp=0x2a01fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by main.(*App).withAppPlayer in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:258 +0x3d8 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 1 gp=0x2802128 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2856d00 sp=0x2856cec pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x2856e00, 0x2a05dcc, 0x0, 0x0, 0x2, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2856da0 sp=0x2856d00 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x2829320, 0x2856e90) Dec 16 12:27:09 living-room go-librespot[4285]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x2856e44 sp=0x2856da0 pc=0x5a8088 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).withAppPlayer(0x2944c00, {0x8874f0, 0xd0ad00}, 0x291f510) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:321 +0x554 fp=0x2856ed0 sp=0x2856e44 pc=0x63db54 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).withCredentials(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:193 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).SpotifyToken(0x2944c00, {0x8874f0, 0xd0ad00}, {0x29428e0, 0x1c}, {0x29183c0, 0x13c}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x2856f00 sp=0x2856ed0 pc=0x63cfa8 Dec 16 12:27:09 living-room go-librespot[4285]: main.main() Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:557 +0x588 fp=0x2856fa8 sp=0x2856f00 pc=0x63feb4 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.main() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2856fec sp=0x2856fa8 pc=0x5cb30 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2856fec sp=0x2856fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 2 gp=0x28027e8 m=nil [force gc (idle)]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2844fd4 sp=0x2844fc0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goparkunlock(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:408 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.forcegchelper() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2844fec sp=0x2844fd4 pc=0x5cefc Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2844fec sp=0x2844fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.init.5 in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:314 +0x1c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 18 gp=0x2884248 m=nil [GC sweep wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28407c4 sp=0x28407b0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goparkunlock(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:408 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.bgsweep(0x2890000) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x28407e4 sp=0x28407c4 pc=0x45848 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcenable.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x28407ec sp=0x28407e4 pc=0x35968 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28407ec sp=0x28407ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcenable in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 19 gp=0x2884368 m=nil [GC scavenge wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2840fb4 sp=0x2840fa0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goparkunlock(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:408 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.(*scavengerState).park(0xce0208) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2840fc8 sp=0x2840fb4 pc=0x42b68 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.bgscavenge(0x2890000) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2840fe4 sp=0x2840fc8 pc=0x432a0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcenable.gowrap2() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2840fec sp=0x2840fe4 pc=0x35914 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2840fec sp=0x2840fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcenable in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 34 gp=0x2906248 m=nil [finalizer wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292c78c sp=0x292c778 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.runfinq() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x292c7ec sp=0x292c78c pc=0x34754 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292c7ec sp=0x292c7ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.createfing in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 37 gp=0x29078c8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035ec8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2cf4d28 sp=0x2cf4d14 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035eb8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2cf4d40 sp=0x2cf4d28 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035eb8, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2cf4d54 sp=0x2cf4d40 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x291b3c8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2cf4d68 sp=0x2cf4d54 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Accept(0x291b3b0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2cf4db0 sp=0x2cf4d68 pc=0x10eb84 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).accept(0x291b3b0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2cf4e18 sp=0x2cf4db0 pc=0x17e4c4 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPListener).accept(0x2930810) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2cf4e30 sp=0x2cf4e18 pc=0x196900 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPListener).Accept(0x2930810) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2cf4e4c sp=0x2cf4e30 pc=0x19596c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*onceCloseListener).Accept(0x2a94340) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x34 fp=0x2cf4e64 sp=0x2cf4e4c pc=0x362b54 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve(0x2a8c0a8, {0x886cb8, 0x2930810}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2cf4efc sp=0x2cf4e64 pc=0x341210 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.Serve(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2794 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve(0x2921080) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x2cf4fe4 sp=0x2cf4efc pc=0x6330b8 Dec 16 12:27:09 living-room go-librespot[4285]: main.NewApiServer.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x2cf4fec sp=0x2cf4fe4 pc=0x631de4 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2cf4fec sp=0x2cf4fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by main.NewApiServer in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:296 +0x310 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 6 gp=0x29079e8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035ce8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x287be84 sp=0x287be70 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035cd8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x287be9c sp=0x287be84 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035cd8, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x287beb0 sp=0x287be9c pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x291be68, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x287bec4 sp=0x287beb0 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).RawRead(0x291be50, 0x295e480) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x287bef0 sp=0x287bec4 pc=0x10f788 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*rawConn).Read(0x2912f00, 0x295e480) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x287bf10 sp=0x287bef0 pc=0x190d28 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2cd4150, 0x295e3f0, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x287bf40 sp=0x287bf10 pc=0x548824 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2a82db8, {0x2a34000, 0x10000, 0x10000}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x287bf9c sp=0x287bf40 pc=0x54c80c Dec 16 12:27:09 living-room go-librespot[4285]: github.com/grandcat/zeroconf.(*Server).recv4(0x2a547c0, 0x2a82db0) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x287bfe0 sp=0x287bf9c pc=0x5a309c Dec 16 12:27:09 living-room go-librespot[4285]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x287bfec sp=0x287bfe0 pc=0x5a2d54 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x287bfec sp=0x287bfec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 21 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 7 gp=0x2907b08 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035c48, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292f65c sp=0x292f648 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035c38, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x292f674 sp=0x292f65c pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035c38, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x292f688 sp=0x292f674 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x291beb8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x292f69c sp=0x292f688 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).RawRead(0x291bea0, 0x2d59410) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x292f6c8 sp=0x292f69c pc=0x10f788 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*rawConn).Read(0x2912f20, 0x2d59410) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x292f6e8 sp=0x292f6c8 pc=0x190d28 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2cd4160, 0x2d593e0, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x292f718 sp=0x292f6e8 pc=0x548824 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 16 12:27:09 living-room go-librespot[4285]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2a82de8, {0x2a24000, 0x10000, 0x10000}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x292f79c sp=0x292f718 pc=0x550194 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/grandcat/zeroconf.(*Server).recv6(0x2a547c0, 0x2a82de0) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x292f7e0 sp=0x292f79c pc=0x5a3290 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x292f7ec sp=0x292f7e0 pc=0x5a2cf8 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292f7ec sp=0x292f7ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 21 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 4 gp=0x2907c28 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035e28, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x287aad4 sp=0x287aac0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035e18, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x287aaec sp=0x287aad4 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035e18, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x287ab00 sp=0x287aaec pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x291b558, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x287ab14 sp=0x287ab00 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x291b540, {0x2abe000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x287ab5c sp=0x287ab14 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x291b540, {0x2abe000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x287ab88 sp=0x287ab5c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2a8a1f0, {0x2abe000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x287abb4 sp=0x287ab88 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2a8a1f0, {0x2abe000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x287abd4 sp=0x287abb4 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*atLeastReader).Read(0x2b4b460, {0x2abe000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x287ac00 sp=0x287abd4 pc=0x27c680 Dec 16 12:27:09 living-room go-librespot[4285]: bytes.(*Buffer).ReadFrom(0x2aa618c, {0x883674, 0x2b4b460}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x287ac3c sp=0x287ac00 pc=0x139bd0 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readFromUntil(0x2aa6008, {0x882848, 0x2a8a1f0}, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x287ac64 sp=0x287ac3c pc=0x27c8dc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecordOrCCS(0x2aa6008, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x287adc8 sp=0x287ac64 pc=0x279fdc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecord(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).Read(0x2aa6008, {0x2abf000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x287adf8 sp=0x287adc8 pc=0x27fe4c Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).Read(0x2d59320, {0x2944824, 0x9, 0x9}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x287ae1c sp=0x287adf8 pc=0x2be440 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadAtLeast({0x882a3c, 0x2d59320}, {0x2944824, 0x9, 0x9}, 0x9) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x287ae48 sp=0x287ae1c pc=0x1042c0 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadFull(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:354 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.http2readFrameHeader({0x2944824, 0x9, 0x9}, {0x882a3c, 0x2d59320}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x287ae70 sp=0x287ae48 pc=0x2f733c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Framer).ReadFrame(0x2944800) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x287aeec sp=0x287ae70 pc=0x2f7ad4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2clientConnReadLoop).run(0x287afdc) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x287afa4 sp=0x287aeec pc=0x31c2f0 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2ClientConn).readLoop(0x2971d08) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x287afe4 sp=0x287afa4 pc=0x31b7e4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x287afec sp=0x287afe4 pc=0x314830 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x287afec sp=0x287afec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*http2Transport).newClientConn in goroutine 3 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 52 gp=0x2aa8128 m=nil [GC worker (idle)]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3fbc, 0x293c720, 0x1a, 0xa, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2928790 sp=0x292877c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcBgMarkWorker() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x29287ec sp=0x2928790 pc=0x38590 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x29287ec sp=0x29287ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 20 gp=0x28847e8 m=nil [GC worker (idle)]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3fbc, 0x293c738, 0x1a, 0xa, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2841790 sp=0x284177c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcBgMarkWorker() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x28417ec sp=0x2841790 pc=0x38590 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28417ec sp=0x28417ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 53 gp=0x2aa8248 m=nil [GC worker (idle)]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3fbc, 0x2aa5410, 0x1a, 0xa, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2928f90 sp=0x2928f7c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcBgMarkWorker() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2928fec sp=0x2928f90 pc=0x38590 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2928fec sp=0x2928fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 54 gp=0x2aa8368 m=nil [GC worker (idle)]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3fbc, 0x2aa5428, 0x1a, 0xa, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2929790 sp=0x292977c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gcBgMarkWorker() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x29297ec sp=0x2929790 pc=0x38590 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x29297ec sp=0x29297ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 71 gp=0x2884c68 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2843674 sp=0x2843660 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x2843774, 0x284373c, 0x0, 0x0, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2843714 sp=0x2843674 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x2b88690) Dec 16 12:27:09 living-room go-librespot[4285]: /src/player/player.go:201 +0x1fc fp=0x28437e4 sp=0x2843714 pc=0x513660 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/player/player.go:183 +0x28 fp=0x28437ec sp=0x28437e4 pc=0x513240 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28437ec sp=0x28437ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/player/player.go:183 +0x218 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 112 gp=0x2884d88 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292dea8 sp=0x292de94 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x292dfa4, 0x292df6c, 0x0, 0x0, 0x5, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x292df48 sp=0x292dea8 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2b92508) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x292dfe4 sp=0x292df48 pc=0x474738 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.newConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x292dfec sp=0x292dfe4 pc=0x47432c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292dfec sp=0x292dfec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by nhooyr.io/websocket.newConn in goroutine 91 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 84 gp=0x2885448 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb60357e8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x287587c sp=0x2875868 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb60357d8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2875894 sp=0x287587c pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb60357d8, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x28758a8 sp=0x2875894 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2cfe8d8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28758bc sp=0x28758a8 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2cfe8c0, {0x2ba8000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2875904 sp=0x28758bc pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2cfe8c0, {0x2ba8000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2875930 sp=0x2875904 pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2912348, {0x2ba8000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x287595c sp=0x2875930 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2912348, {0x2ba8000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x287597c sp=0x287595c pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: io.(*multiReader).Read(0x2cd41f0, {0x2ba8000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x28759a8 sp=0x287597c pc=0x105528 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).fill(0x295e1b0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x28759cc sp=0x28759a8 pc=0x2bddb4 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).ReadByte(0x295e1b0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x28759d8 sp=0x28759cc pc=0x2be638 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.readFrameHeader(0x295e1b0, {0x29f4678, 0x8, 0x8}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2875a18 sp=0x28759d8 pc=0x47788c Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x29f4648, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2875ad8 sp=0x2875a18 pc=0x47a4f0 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readLoop(0x29f4648, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2875b9c sp=0x2875ad8 pc=0x479fd8 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).reader(0x29f4648, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2875c28 sp=0x2875b9c pc=0x47b1ec Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Read(0x29f4648, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2875c50 sp=0x2875c28 pc=0x4798bc Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2875ccc sp=0x2875c50 pc=0x633988 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a8a180, {0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2875cdc sp=0x2875ccc pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*ServeMux).ServeHTTP(0x2a80000, {0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2875d18 sp=0x2875cdc pc=0x33f654 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2875d40 sp=0x2875d18 pc=0x633388 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a84160, {0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2875d50 sp=0x2875d40 pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.serverHandler.ServeHTTP({0x2a8c0a8}, {0x886dec, 0x2d0a148}, 0x2d0a0a8) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2875d6c sp=0x2875d50 pc=0x340de4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*conn).serve(0x28fa060, {0x887510, 0x2a96180}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2875fdc sp=0x2875d6c pc=0x33c034 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve.gowrap3() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2875fec sp=0x2875fdc pc=0x341688 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2875fec sp=0x2875fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*Server).Serve in goroutine 37 Dec 16 12:27:09 living-room volumio[1173]: error: Failed to send command to Spotify local API: /player/next: Error: socket hang up Dec 16 12:27:09 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:27:09 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:27:09 living-room volumio[1173]: info: Connection to go-librespot Websocket closed Dec 16 12:27:09 living-room systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 11 gp=0x2885688 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035ba8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2859ad4 sp=0x2859ac0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035b98, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2859aec sp=0x2859ad4 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035b98, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2859b00 sp=0x2859aec pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2ce19b8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2859b14 sp=0x2859b00 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2ce19a0, {0x2d7e000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2859b5c sp=0x2859b14 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2ce19a0, {0x2d7e000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2859b88 sp=0x2859b5c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2893058, {0x2d7e000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2859bb4 sp=0x2859b88 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2893058, {0x2d7e000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x2859bd4 sp=0x2859bb4 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*atLeastReader).Read(0x2b4b7b0, {0x2d7e000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2859c00 sp=0x2859bd4 pc=0x27c680 Dec 16 12:27:09 living-room go-librespot[4285]: bytes.(*Buffer).ReadFrom(0x2c04ccc, {0x883674, 0x2b4b7b0}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2859c3c sp=0x2859c00 pc=0x139bd0 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readFromUntil(0x2c04b48, {0x882848, 0x2893058}, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2859c64 sp=0x2859c3c pc=0x27c8dc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecordOrCCS(0x2c04b48, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2859dc8 sp=0x2859c64 pc=0x279fdc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecord(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).Read(0x2c04b48, {0x2a10000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2859df8 sp=0x2859dc8 pc=0x27fe4c Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).Read(0x2a0c570, {0x2944924, 0x9, 0x9}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2859e1c sp=0x2859df8 pc=0x2be440 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadAtLeast({0x882a3c, 0x2a0c570}, {0x2944924, 0x9, 0x9}, 0x9) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2859e48 sp=0x2859e1c pc=0x1042c0 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadFull(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:354 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.http2readFrameHeader({0x2944924, 0x9, 0x9}, {0x882a3c, 0x2a0c570}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2859e70 sp=0x2859e48 pc=0x2f733c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Framer).ReadFrame(0x2944900) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2859eec sp=0x2859e70 pc=0x2f7ad4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2clientConnReadLoop).run(0x2859fdc) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2859fa4 sp=0x2859eec pc=0x31c2f0 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2ClientConn).readLoop(0x2971e08) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2859fe4 sp=0x2859fa4 pc=0x31b7e4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2859fec sp=0x2859fe4 pc=0x314830 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2859fec sp=0x2859fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*http2Transport).newClientConn in goroutine 10 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 113 gp=0x28857a8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb60356a8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292e6b4 sp=0x292e6a0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035698, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x292e6cc sp=0x292e6b4 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035698, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x292e6e0 sp=0x292e6cc pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2816158, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x292e6f4 sp=0x292e6e0 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2816140, {0x2a9502d, 0x1, 0x1}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x292e73c sp=0x292e6f4 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2816140, {0x2a9502d, 0x1, 0x1}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x292e768 sp=0x292e73c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x28bc2a8, {0x2a9502d, 0x1, 0x1}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x292e794 sp=0x292e768 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x28bc2a8, {0x2a9502d, 0x1, 0x1}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x292e7b4 sp=0x292e794 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*connReader).backgroundRead(0x2a95020) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x292e7e4 sp=0x292e7b4 pc=0x335cfc Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*connReader).startBackgroundRead.gowrap2() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x292e7ec sp=0x292e7e4 pc=0x335c34 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292e7ec sp=0x292e7ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*connReader).startBackgroundRead in goroutine 44 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:677 +0x160 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 69 gp=0x28858c8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035a68, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2874ad4 sp=0x2874ac0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035a58, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2874aec sp=0x2874ad4 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035a58, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2874b00 sp=0x2874aec pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2cfe6a8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2874b14 sp=0x2874b00 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2cfe690, {0x2a78000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2874b5c sp=0x2874b14 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2cfe690, {0x2a78000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2874b88 sp=0x2874b5c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2a6a570, {0x2a78000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2874bb4 sp=0x2874b88 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2a6a570, {0x2a78000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x2874bd4 sp=0x2874bb4 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*atLeastReader).Read(0x2cd4c70, {0x2a78000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2874c00 sp=0x2874bd4 pc=0x27c680 Dec 16 12:27:09 living-room go-librespot[4285]: bytes.(*Buffer).ReadFrom(0x2c0580c, {0x883674, 0x2cd4c70}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2874c3c sp=0x2874c00 pc=0x139bd0 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readFromUntil(0x2c05688, {0x882848, 0x2a6a570}, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2874c64 sp=0x2874c3c pc=0x27c8dc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecordOrCCS(0x2c05688, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2874dc8 sp=0x2874c64 pc=0x279fdc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecord(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).Read(0x2c05688, {0x2b82000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2874df8 sp=0x2874dc8 pc=0x27fe4c Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).Read(0x2a638f0, {0x2944ba4, 0x9, 0x9}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2874e1c sp=0x2874df8 pc=0x2be440 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadAtLeast({0x882a3c, 0x2a638f0}, {0x2944ba4, 0x9, 0x9}, 0x9) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2874e48 sp=0x2874e1c pc=0x1042c0 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadFull(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:354 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.http2readFrameHeader({0x2944ba4, 0x9, 0x9}, {0x882a3c, 0x2a638f0}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2874e70 sp=0x2874e48 pc=0x2f733c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Framer).ReadFrame(0x2944b80) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2874eec sp=0x2874e70 pc=0x2f7ad4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2clientConnReadLoop).run(0x2874fdc) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2874fa4 sp=0x2874eec pc=0x31c2f0 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2ClientConn).readLoop(0x2971f08) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2874fe4 sp=0x2874fa4 pc=0x31b7e4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2874fec sp=0x2874fe4 pc=0x314830 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2874fec sp=0x2874fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*http2Transport).newClientConn in goroutine 68 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 73 gp=0x2aa8a28 m=nil [chan receive]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3f90, 0x2900270, 0xe, 0x7, 0x2) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2929f48 sp=0x2929f34 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv(0x2900240, 0x2929fd0, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2929f84 sp=0x2929f48 pc=0x20078 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv2(0x2900240, 0x2929fd0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2929f98 sp=0x2929f84 pc=0x1fbbc Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).withAppPlayer.func1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:268 +0x68 fp=0x2929fec sp=0x2929f98 pc=0x63e794 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2929fec sp=0x2929fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by main.(*App).withAppPlayer in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:265 +0x464 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 74 gp=0x2aa8b48 m=nil [chan receive]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3f90, 0x2900230, 0xe, 0x7, 0x2) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292a740 sp=0x292a72c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv(0x2900200, 0x292a7e0, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x292a77c sp=0x292a740 pc=0x20078 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv2(0x2900200, 0x292a7e0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x292a790 sp=0x292a77c pc=0x1fbbc Dec 16 12:27:09 living-room go-librespot[4285]: main.(*App).withAppPlayer.func2() Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:284 +0x6c fp=0x292a7ec sp=0x292a790 pc=0x63e330 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292a7ec sp=0x292a7ec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by main.(*App).withAppPlayer in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/main.go:281 +0x504 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 75 gp=0x2aa8c68 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035d88, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292ade8 sp=0x292add4 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035d78, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x292ae00 sp=0x292ade8 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035d78, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x292ae14 sp=0x292ae00 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x291be18, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x292ae28 sp=0x292ae14 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Accept(0x291be00) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x292ae70 sp=0x292ae28 pc=0x10eb84 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).accept(0x291be00) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x292aed8 sp=0x292ae70 pc=0x17e4c4 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPListener).accept(0x2a97680) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x292aef0 sp=0x292aed8 pc=0x196900 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPListener).Accept(0x2a97680) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x292af0c sp=0x292aef0 pc=0x19596c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*onceCloseListener).Accept(0x2894be0) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x34 fp=0x292af24 sp=0x292af0c pc=0x362b54 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve(0x2ba0288, {0x886cb8, 0x2a97680}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x292afbc sp=0x292af24 pc=0x341210 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.Serve(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2794 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 16 12:27:09 living-room go-librespot[4285]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x292afec sp=0x292afbc pc=0x5a8184 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292afec sp=0x292afec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 16 12:27:09 living-room go-librespot[4285]: /src/zeroconf/zeroconf.go:280 +0x160 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 99 gp=0x2aa8d88 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292cea8 sp=0x292ce94 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x292cfa4, 0x292cf6c, 0x0, 0x0, 0x5, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x292cf48 sp=0x292cea8 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2b92148) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x292cfe4 sp=0x292cf48 pc=0x474738 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.newConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x292cfec sp=0x292cfe4 pc=0x47432c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292cfec sp=0x292cfec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by nhooyr.io/websocket.newConn in goroutine 82 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 91 gp=0x2aa8ea8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035748, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x287787c sp=0x2877868 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035738, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2877894 sp=0x287787c pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035738, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x28778a8 sp=0x2877894 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2cfeba8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28778bc sp=0x28778a8 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2cfeb90, {0x2bad000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2877904 sp=0x28778bc pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2cfeb90, {0x2bad000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2877930 sp=0x2877904 pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2912490, {0x2bad000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x287795c sp=0x2877930 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2912490, {0x2bad000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x287797c sp=0x287795c pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: io.(*multiReader).Read(0x2a4fba0, {0x2bad000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x28779a8 sp=0x287797c pc=0x105528 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).fill(0x295e330) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x28779cc sp=0x28779a8 pc=0x2bddb4 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).ReadByte(0x295e330) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x28779d8 sp=0x28779cc pc=0x2be638 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.readFrameHeader(0x295e330, {0x2b92538, 0x8, 0x8}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2877a18 sp=0x28779d8 pc=0x47788c Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2b92508, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2877ad8 sp=0x2877a18 pc=0x47a4f0 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readLoop(0x2b92508, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2877b9c sp=0x2877ad8 pc=0x479fd8 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).reader(0x2b92508, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2877c28 sp=0x2877b9c pc=0x47b1ec Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Read(0x2b92508, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2877c50 sp=0x2877c28 pc=0x4798bc Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2877ccc sp=0x2877c50 pc=0x633988 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a8a180, {0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2877cdc sp=0x2877ccc pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*ServeMux).ServeHTTP(0x2a80000, {0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2877d18 sp=0x2877cdc pc=0x33f654 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2877d40 sp=0x2877d18 pc=0x633388 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a84160, {0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2877d50 sp=0x2877d40 pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.serverHandler.ServeHTTP({0x2a8c0a8}, {0x886dec, 0x2ba0828}, 0x2d0a468) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2877d6c sp=0x2877d50 pc=0x340de4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*conn).serve(0x28fa1e0, {0x887510, 0x2a96180}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2877fdc sp=0x2877d6c pc=0x33c034 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve.gowrap3() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2877fec sp=0x2877fdc pc=0x341688 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2877fec sp=0x2877fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*Server).Serve in goroutine 37 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 86 gp=0x2aa8fc8 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292eea8 sp=0x292ee94 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x292efa4, 0x292ef6c, 0x0, 0x0, 0x5, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x292ef48 sp=0x292eea8 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x29f4648) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x292efe4 sp=0x292ef48 pc=0x474738 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.newConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x292efec sp=0x292efe4 pc=0x47432c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292efec sp=0x292efec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by nhooyr.io/websocket.newConn in goroutine 84 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 82 gp=0x2aa8008 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035888, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x285b87c sp=0x285b868 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035878, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x285b894 sp=0x285b87c pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035878, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x285b8a8 sp=0x285b894 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2cfe608, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x285b8bc sp=0x285b8a8 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2cfe5f0, {0x2af7000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x285b904 sp=0x285b8bc pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2cfe5f0, {0x2af7000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x285b930 sp=0x285b904 pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2912020, {0x2af7000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x285b95c sp=0x285b930 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2912020, {0x2af7000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x285b97c sp=0x285b95c pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: io.(*multiReader).Read(0x2a4e560, {0x2af7000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x285b9a8 sp=0x285b97c pc=0x105528 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).fill(0x2ba48d0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x285b9cc sp=0x285b9a8 pc=0x2bddb4 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).ReadByte(0x2ba48d0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x285b9d8 sp=0x285b9cc pc=0x2be638 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.readFrameHeader(0x2ba48d0, {0x2b92178, 0x8, 0x8}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x285ba18 sp=0x285b9d8 pc=0x47788c Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2b92148, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x285bad8 sp=0x285ba18 pc=0x47a4f0 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readLoop(0x2b92148, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x285bb9c sp=0x285bad8 pc=0x479fd8 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).reader(0x2b92148, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x285bc28 sp=0x285bb9c pc=0x47b1ec Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Read(0x2b92148, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x285bc50 sp=0x285bc28 pc=0x4798bc Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x285bccc sp=0x285bc50 pc=0x633988 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a8a180, {0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x285bcdc sp=0x285bccc pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*ServeMux).ServeHTTP(0x2a80000, {0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x285bd18 sp=0x285bcdc pc=0x33f654 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x285bd40 sp=0x285bd18 pc=0x633388 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a84160, {0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x285bd50 sp=0x285bd40 pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.serverHandler.ServeHTTP({0x2a8c0a8}, {0x886dec, 0x2ba00a8}, 0x2ba0008) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x285bd6c sp=0x285bd50 pc=0x340de4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*conn).serve(0x28fa000, {0x887510, 0x2a96180}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x285bfdc sp=0x285bd6c pc=0x33c034 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve.gowrap3() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x285bfec sp=0x285bfdc pc=0x341688 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x285bfec sp=0x285bfec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*Server).Serve in goroutine 37 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 100 gp=0x2906ea8 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2841ea8 sp=0x2841e94 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x2841fa4, 0x2841f6c, 0x0, 0x0, 0x5, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2841f48 sp=0x2841ea8 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2b92288) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2841fe4 sp=0x2841f48 pc=0x474738 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.newConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2841fec sp=0x2841fe4 pc=0x47432c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2841fec sp=0x2841fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by nhooyr.io/websocket.newConn in goroutine 72 Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 101 gp=0x2906fc8 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035b08, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2878d14 sp=0x2878d00 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035af8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2878d2c sp=0x2878d14 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035af8, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2878d40 sp=0x2878d2c pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2cfe658, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2878d54 sp=0x2878d40 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2cfe640, {0x28a748c, 0x3, 0x3}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2878d9c sp=0x2878d54 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2cfe640, {0x28a748c, 0x3, 0x3}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2878dc8 sp=0x2878d9c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x28bc020, {0x28a748c, 0x3, 0x3}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2878df4 sp=0x2878dc8 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x28bc020, {0x28a748c, 0x3, 0x3}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x2878e14 sp=0x2878df4 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadAtLeast({0x882848, 0x28bc020}, {0x28a748c, 0x3, 0x3}, 0x3) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2878e40 sp=0x2878e14 pc=0x1042c0 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadFull(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:354 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2897c80, {0x8874f0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/shannon.go:124 +0x3a0 fp=0x2878f04 sp=0x2878e40 pc=0x48faf4 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2cc09a8) Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:276 +0x68 fp=0x2878fe4 sp=0x2878f04 pc=0x48c330 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:260 +0x28 fp=0x2878fec sp=0x2878fe4 pc=0x48c29c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2878fec sp=0x2878fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 72 Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:260 +0x98 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 102 gp=0x29070e8 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292beb8 sp=0x292bea4 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x292bfd4, 0x292bf7c, 0x0, 0x0, 0x2, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x292bf58 sp=0x292beb8 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2cc09a8) Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:355 +0x8c fp=0x292bfe4 sp=0x292bf58 pc=0x48cb50 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:264 +0x28 fp=0x292bfec sp=0x292bfe4 pc=0x48c248 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292bfec sp=0x292bfec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 72 Dec 16 12:27:09 living-room go-librespot[4285]: /src/ap/ap.go:264 +0x15c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 103 gp=0x2907328 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb60359c8, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2a03940 sp=0x2a0392c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb60359b8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2a03958 sp=0x2a03940 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb60359b8, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2a0396c sp=0x2a03958 pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2b88838, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2a03980 sp=0x2a0396c pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2b88820, {0x2af6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2a039c8 sp=0x2a03980 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2b88820, {0x2af6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2a039f4 sp=0x2a039c8 pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x28bc438, {0x2af6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2a03a20 sp=0x2a039f4 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x28bc438, {0x2af6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x2a03a40 sp=0x2a03a20 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*atLeastReader).Read(0x2a4f930, {0x2af6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2a03a6c sp=0x2a03a40 pc=0x27c680 Dec 16 12:27:09 living-room go-librespot[4285]: bytes.(*Buffer).ReadFrom(0x2b42ccc, {0x883674, 0x2a4f930}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2a03aa8 sp=0x2a03a6c pc=0x139bd0 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readFromUntil(0x2b42b48, {0x882848, 0x28bc438}, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2a03ad0 sp=0x2a03aa8 pc=0x27c8dc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecordOrCCS(0x2b42b48, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2a03c34 sp=0x2a03ad0 pc=0x279fdc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecord(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).Read(0x2b42b48, {0x2afa000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2a03c64 sp=0x2a03c34 pc=0x27fe4c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*readWriteCloserBody).Read(0x2cd4010, {0x2afa000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x2a03c84 sp=0x2a03c64 pc=0x357b00 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).fill(0x2ba49f0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2a03ca8 sp=0x2a03c84 pc=0x2bddb4 Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).ReadByte(0x2ba49f0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x2a03cb4 sp=0x2a03ca8 pc=0x2be638 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.readFrameHeader(0x2ba49f0, {0x2b922b8, 0x8, 0x8}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2a03cf4 sp=0x2a03cb4 pc=0x47788c Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2b92288, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2a03db4 sp=0x2a03cf4 pc=0x47a4f0 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).readLoop(0x2b92288, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2a03e78 sp=0x2a03db4 pc=0x479fd8 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).reader(0x2b92288, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2a03f04 sp=0x2a03e78 pc=0x47b1ec Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 16 12:27:09 living-room go-librespot[4285]: nhooyr.io/websocket.(*Conn).Read(0x2b92288, {0x8874d0, 0xd0ad00}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2a03f2c sp=0x2a03f04 pc=0x4798bc Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2d64848) Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:188 +0x58 fp=0x2a03fe4 sp=0x2a03f2c pc=0x531d64 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:125 +0x28 fp=0x2a03fec sp=0x2a03fe4 pc=0x53383c Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2a03fec sp=0x2a03fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 72 Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:125 +0x98 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 104 gp=0x2907448 m=nil [select]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x292fe98 sp=0x292fe84 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.selectgo(0x292ffd4, 0x292ff60, 0x0, 0x0, 0x2, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x292ff38 sp=0x292fe98 pc=0x70e78 Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2d64848) Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:138 +0x8c fp=0x292ffe4 sp=0x292ff38 pc=0x5318dc Dec 16 12:27:09 living-room go-librespot[4285]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:129 +0x28 fp=0x292ffec sp=0x292ffe4 pc=0x5337e8 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x292ffec sp=0x292ffec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 72 Dec 16 12:27:09 living-room go-librespot[4285]: /src/dealer/dealer.go:129 +0x15c Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 108 gp=0x2a5d688 m=nil [IO wait]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e40f0, 0xb6035928, 0x2, 0x2, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2879ad4 sp=0x2879ac0 pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.netpollblock(0xb6035918, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2879aec sp=0x2879ad4 pc=0x548cc Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.runtime_pollWait(0xb6035918, 0x72) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2879b00 sp=0x2879aec pc=0x8f4a8 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).wait(0x2aeebf8, 0x72, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2879b14 sp=0x2879b00 pc=0x10a318 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*pollDesc).waitRead(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 16 12:27:09 living-room go-librespot[4285]: internal/poll.(*FD).Read(0x2aeebe0, {0x28b4000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2879b5c sp=0x2879b14 pc=0x10b504 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*netFD).Read(0x2aeebe0, {0x28b4000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2879b88 sp=0x2879b5c pc=0x17c5d0 Dec 16 12:27:09 living-room go-librespot[4285]: net.(*conn).Read(0x2a6abc8, {0x28b4000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2879bb4 sp=0x2879b88 pc=0x18caec Dec 16 12:27:09 living-room go-librespot[4285]: net.(*TCPConn).Read(0x2a6abc8, {0x28b4000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: :1 +0x44 fp=0x2879bd4 sp=0x2879bb4 pc=0x19fd40 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*atLeastReader).Read(0x2a4fb70, {0x28b4000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2879c00 sp=0x2879bd4 pc=0x27c680 Dec 16 12:27:09 living-room go-librespot[4285]: bytes.(*Buffer).ReadFrom(0x2aa6ccc, {0x883674, 0x2a4fb70}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2879c3c sp=0x2879c00 pc=0x139bd0 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readFromUntil(0x2aa6b48, {0x882848, 0x2a6abc8}, 0x5) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2879c64 sp=0x2879c3c pc=0x27c8dc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecordOrCCS(0x2aa6b48, 0x0) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2879dc8 sp=0x2879c64 pc=0x279fdc Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).readRecord(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 16 12:27:09 living-room go-librespot[4285]: crypto/tls.(*Conn).Read(0x2aa6b48, {0x28b6000, 0x1000, 0x1000}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2879df8 sp=0x2879dc8 pc=0x27fe4c Dec 16 12:27:09 living-room go-librespot[4285]: bufio.(*Reader).Read(0x2ba5d10, {0x2944f24, 0x9, 0x9}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2879e1c sp=0x2879df8 pc=0x2be440 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadAtLeast({0x882a3c, 0x2ba5d10}, {0x2944f24, 0x9, 0x9}, 0x9) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2879e48 sp=0x2879e1c pc=0x1042c0 Dec 16 12:27:09 living-room go-librespot[4285]: io.ReadFull(...) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/io/io.go:354 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.http2readFrameHeader({0x2944f24, 0x9, 0x9}, {0x882a3c, 0x2ba5d10}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2879e70 sp=0x2879e48 pc=0x2f733c Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Framer).ReadFrame(0x2944f00) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2879eec sp=0x2879e70 pc=0x2f7ad4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2clientConnReadLoop).run(0x2879fdc) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2879fa4 sp=0x2879eec pc=0x31c2f0 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2ClientConn).readLoop(0x2cd6108) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2879fe4 sp=0x2879fa4 pc=0x31b7e4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2879fec sp=0x2879fe4 pc=0x314830 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2879fec sp=0x2879fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*http2Transport).newClientConn in goroutine 107 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 16 12:27:09 living-room go-librespot[4285]: goroutine 44 gp=0x2bc8008 m=nil [chan receive]: Dec 16 12:27:09 living-room go-librespot[4285]: runtime.gopark(0x7e3f90, 0x2ba3270, 0xe, 0x7, 0x2) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2c12bb0 sp=0x2c12b9c pc=0x5d0c0 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv(0x2ba3240, 0x2c12c7c, 0x1) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2c12bec sp=0x2c12bb0 pc=0x20078 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.chanrecv1(0x2ba3240, 0x2c12c7c) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x2c12c00 sp=0x2c12bec pc=0x1fb98 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).handleRequest(0x2921080, {{0x791346, 0x4}, {0x6c9bb0, 0x0}, 0x2ba3240}, {0x886dec, 0x2ba08c8}) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:319 +0x74 fp=0x2c12c8c sp=0x2c12c00 pc=0x631f94 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.func8({0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /src/cmd/daemon/api_server.go:450 +0x12c fp=0x2c12ccc sp=0x2c12c8c pc=0x6347cc Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a8a0a8, {0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2c12cdc sp=0x2c12ccc pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*ServeMux).ServeHTTP(0x2a80000, {0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2c12d18 sp=0x2c12cdc pc=0x33f654 Dec 16 12:27:09 living-room go-librespot[4285]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2c12d40 sp=0x2c12d18 pc=0x633388 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.HandlerFunc.ServeHTTP(0x2a84160, {0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2c12d50 sp=0x2c12d40 pc=0x33d7f4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.serverHandler.ServeHTTP({0x2a8c0a8}, {0x886dec, 0x2ba08c8}, 0x2924d28) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2c12d6c sp=0x2c12d50 pc=0x340de4 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*conn).serve(0x2814180, {0x887510, 0x2a96180}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2c12fdc sp=0x2c12d6c pc=0x33c034 Dec 16 12:27:09 living-room go-librespot[4285]: net/http.(*Server).Serve.gowrap3() Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2c12fec sp=0x2c12fdc pc=0x341688 Dec 16 12:27:09 living-room go-librespot[4285]: runtime.goexit({}) Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2c12fec sp=0x2c12fec pc=0x93d6c Dec 16 12:27:09 living-room go-librespot[4285]: created by net/http.(*Server).Serve in goroutine 37 Dec 16 12:27:09 living-room go-librespot[4285]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 16 12:27:09 living-room go-librespot[4284]: Aborted Dec 16 12:27:10 living-room volumio[1173]: info: Getting Spotify volume Dec 16 12:27:10 living-room volumio[1173]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 12:27:10 living-room volumio[1173]: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 16 12:27:10 living-room volumio[1173]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Dec 16 12:27:10 living-room volumio[1173]: errno: -111, Dec 16 12:27:10 living-room volumio[1173]: code: 'ECONNREFUSED', Dec 16 12:27:10 living-room volumio[1173]: syscall: 'connect', Dec 16 12:27:10 living-room volumio[1173]: address: '127.0.0.1', Dec 16 12:27:10 living-room volumio[1173]: port: 9879, Dec 16 12:27:10 living-room volumio[1173]: response: undefined Dec 16 12:27:10 living-room volumio[1173]: } Dec 16 12:27:10 living-room volumio[1173]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 16 12:27:10 living-room sudo[4326]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-16 12:26' Dec 16 12:27:10 living-room sudo[4326]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"