Jan 08 18:22:00 volumio go-librespot[2068]: time="2026-01-08T18:22:00Z" level=trace msg="sent dealer ping" Jan 08 18:22:00 volumio go-librespot[2068]: time="2026-01-08T18:22:00Z" level=trace msg="received dealer pong" Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 08 18:22:06 volumio volumio[1444]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 08 18:22:06 volumio volumio[1444]: info: Received Get System Version Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 08 18:22:06 volumio volumio[1444]: info: Received Get System Info Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 08 18:22:06 volumio volumio[1444]: info: Discovery: Getting this device information Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::volumioGetState Jan 08 18:22:06 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 08 18:22:15 volumio go-librespot[2068]: time="2026-01-08T18:22:15Z" level=debug msg="prefetching next track" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:15 volumio go-librespot[2068]: time="2026-01-08T18:22:15Z" level=debug msg="selected format OGG_VORBIS_320 (a10a067ababf6dcd1d4e80f4b1363f4ed6645e68)" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:15 volumio go-librespot[2068]: time="2026-01-08T18:22:15Z" level=debug msg="requested aes key for file a10a067ababf6dcd1d4e80f4b1363f4ed6645e68, gid: 5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:15 volumio go-librespot[2068]: time="2026-01-08T18:22:15Z" level=trace msg="found 2 cdn urls" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:16 volumio go-librespot[2068]: time="2026-01-08T18:22:16Z" level=debug msg="fetched first chunk of 16, total size is 8320202 bytes" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:16 volumio go-librespot[2068]: time="2026-01-08T18:22:16Z" level=info msg="prefetched track \"PA PA IUBIRE\" (duration: 205434ms)" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:16 volumio go-librespot[2068]: time="2026-01-08T18:22:16Z" level=debug msg="fetched chunk 2/15, size: 524288" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:16 volumio go-librespot[2068]: time="2026-01-08T18:22:16Z" level=debug msg="fetched chunk 3/15, size: 524288" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:16 volumio go-librespot[2068]: time="2026-01-08T18:22:16Z" level=debug msg="fetched chunk 1/15, size: 524288" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:21 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: youtube2 , handleBrowseUri Jan 08 18:22:21 volumio volumio[1444]: info: [youtube2-browse] browseUri: youtube2 Jan 08 18:22:21 volumio volumio[1444]: info: [youtube2] InnertubeLoader: creating Innertube instance... Jan 08 18:22:22 volumio volumio[1444]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jan 08 18:22:30 volumio go-librespot[2068]: time="2026-01-08T18:22:30Z" level=trace msg="sent dealer ping" Jan 08 18:22:30 volumio go-librespot[2068]: time="2026-01-08T18:22:30Z" level=trace msg="received dealer pong" Jan 08 18:22:35 volumio volumio[1444]: info: [youtube2] InnertubeLoader: obtaining po_token by visitorData... Jan 08 18:22:35 volumio volumio[1444]: Searching plugin music_service/youtube2 Jan 08 18:22:35 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Jan 08 18:22:36 volumio volumio[1444]: info: [youtube2] InnertubeLoader: obtained po_token (expires in 43200 seconds) Jan 08 18:22:36 volumio volumio[1444]: info: [youtube2] InnertubeLoader: re-create Innertube instance with po_token Jan 08 18:22:36 volumio volumio[1444]: info: [youtube2] InnertubeLoader: creating Innertube instance with po_token... Jan 08 18:22:36 volumio volumio[1444]: Searching plugin music_service/youtube2 Jan 08 18:22:36 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Jan 08 18:22:36 volumio volumio[1444]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=trace msg="emitting websocket event: not_playing" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=trace msg="emitting websocket event: will_play" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=info msg="loaded track \"PA PA IUBIRE\" (paused: false, position: 0ms, duration: 205434ms, prefetched: true)" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=trace msg="scheduling prefetch in 175s" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=trace msg="emitting websocket event: metadata" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Jan 08 18:22:45 volumio go-librespot[2068]: time="2026-01-08T18:22:45Z" level=trace msg="emitting websocket event: playing" Jan 08 18:22:48 volumio volumio[1444]: info: [youtube2] InnertubeLoader: going to refresh po_token in 43100 seconds Jan 08 18:22:48 volumio volumio[1444]: Searching plugin music_service/youtube2 Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: youtube2 , search Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: received: {"type":"not_playing","data":{"context_uri":"spotify:search:ningea+cu+flori+de+iasomii","uri":"spotify:track:2mT2vMtey8Dd5fTF7tOiM2","play_origin":"search"}} Jan 08 18:22:48 volumio volumio[1444]: error: Failed to decode event: not_playing Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:search:ningea+cu+flori+de+iasomii","uri":"spotify:track:5QNFNjZbwfP6ah7NpDelXV","play_origin":"search"}} Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:5QNFNjZbwfP6ah7NpDelXV","name":"PA PA IUBIRE","artist_names":["Denisa"],"album_name":"Manele VECHI TOP","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02503e3f6fa71eb7e9753a3e8d","position":0,"duration":205434,"release_date":"year:2024 month:7 day:25","track_number":4,"disc_number":1}} Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:search:ningea+cu+flori+de+iasomii","uri":"spotify:track:5QNFNjZbwfP6ah7NpDelXV","resume":false,"play_origin":"search"}} Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: PUSH STATE SPOTIFY Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: {"status":"play","service":"spop","title":"PA PA IUBIRE","artist":"Denisa","album":"Manele VECHI TOP","albumart":"https://i.scdn.co/image/ab67616d00001e02503e3f6fa71eb7e9753a3e8d","uri":"spotify:track:5QNFNjZbwfP6ah7NpDelXV","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::servicePushState Jan 08 18:22:48 volumio volumio[1444]: info: CoreStateMachine::pushState Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::volumioPushState Jan 08 18:22:48 volumio volumio[1444]: info: Preload queue cleared Jan 08 18:22:48 volumio volumio[1444]: info: Preload queue cleared Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: PUSH STATE SPOTIFY Jan 08 18:22:48 volumio volumio[1444]: SPOTIFY: {"status":"play","service":"spop","title":"PA PA IUBIRE","artist":"Denisa","album":"Manele VECHI TOP","albumart":"https://i.scdn.co/image/ab67616d00001e02503e3f6fa71eb7e9753a3e8d","uri":"spotify:track:5QNFNjZbwfP6ah7NpDelXV","trackType":"spotify","seek":0,"duration":205,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::servicePushState Jan 08 18:22:48 volumio volumio[1444]: info: CoreStateMachine::pushState Jan 08 18:22:48 volumio volumio[1444]: info: CoreCommandRouter::volumioPushState Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 60, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Antonio Pican, BiBi - Toate Fostele (Official Video) · 3:19', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ Jan 08 18:22:49 volumio volumio[1444]: { Jan 08 18:22:49 volumio volumio[1444]: text: 'Antonio Pican, BiBi - Toate Fostele (Official Video) · 3:19', Jan 08 18:22:49 volumio volumio[1444]: startIndex: 0 Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 57, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'BiBi - Nu Te Mai Vrea Fata | Official Music Video · 2:34', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ Jan 08 18:22:49 volumio volumio[1444]: { Jan 08 18:22:49 volumio volumio[1444]: text: 'BiBi - Nu Te Mai Vrea Fata | Official Music Video · 2:34', Jan 08 18:22:49 volumio volumio[1444]: startIndex: 0 Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 26, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { content: 'Prolog - Recitativ · 0:58', commandRuns: [ [Object] ] }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Prolog - Recitativ · 0:58', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 32, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Trecerea Prin Anotimpuri · 3:47', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Trecerea Prin Anotimpuri · 3:47', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: info: All search sources collected, pushing search results Jan 08 18:22:49 volumio volumio[1444]: info: All search sources collected, pushing search results Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 36, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Stefan Hrusca Fostele iubiri · 3:27', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Stefan Hrusca Fostele iubiri · 3:27', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 71, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Ti-as da florile iubirii - versuri & interpretare Daniel Luca · 6:20', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ Jan 08 18:22:49 volumio volumio[1444]: { Jan 08 18:22:49 volumio volumio[1444]: text: 'Ti-as da florile iubirii - versuri & interpretare Daniel Luca · 6:20', Jan 08 18:22:49 volumio volumio[1444]: startIndex: 0 Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 26, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { content: 'Prolog - Recitativ · 0:58', commandRuns: [ [Object] ] }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Prolog - Recitativ · 0:58', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 32, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Trecerea Prin Anotimpuri · 3:47', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Trecerea Prin Anotimpuri · 3:47', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 26, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { content: 'Prolog - Recitativ · 0:58', commandRuns: [ [Object] ] }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Prolog - Recitativ · 0:58', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 32, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Trecerea Prin Anotimpuri · 3:47', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Trecerea Prin Anotimpuri · 3:47', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 26, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { content: 'Prolog - Recitativ · 0:58', commandRuns: [ [Object] ] }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Prolog - Recitativ · 0:58', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: [YOUTUBEJS][Text]: Unable to find matching run for command run. Skipping... { Jan 08 18:22:49 volumio volumio[1444]: command_run: { startIndex: 0, length: 32, onTap: { innertubeCommand: [Object] } }, Jan 08 18:22:49 volumio volumio[1444]: input_data: { Jan 08 18:22:49 volumio volumio[1444]: content: 'Trecerea Prin Anotimpuri · 3:47', Jan 08 18:22:49 volumio volumio[1444]: commandRuns: [ [Object] ] Jan 08 18:22:49 volumio volumio[1444]: }, Jan 08 18:22:49 volumio volumio[1444]: parsed_runs: [ { text: 'Trecerea Prin Anotimpuri · 3:47', startIndex: 0 } ] Jan 08 18:22:49 volumio volumio[1444]: } Jan 08 18:22:49 volumio volumio[1444]: info: All search sources collected, pushing search results Jan 08 18:22:59 volumio go-librespot[2068]: time="2026-01-08T18:22:59Z" level=debug msg="fetched chunk 4/15, size: 524288" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:23:00 volumio go-librespot[2068]: time="2026-01-08T18:23:00Z" level=trace msg="received accesspoint ping" Jan 08 18:23:00 volumio go-librespot[2068]: time="2026-01-08T18:23:00Z" level=trace msg="received accesspoint pong ack" Jan 08 18:23:00 volumio go-librespot[2068]: time="2026-01-08T18:23:00Z" level=trace msg="sent dealer ping" Jan 08 18:23:01 volumio go-librespot[2068]: time="2026-01-08T18:23:01Z" level=trace msg="received dealer pong" Jan 08 18:23:09 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 08 18:23:09 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jan 08 18:23:12 volumio volumio[1444]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 08 18:23:13 volumio go-librespot[2068]: time="2026-01-08T18:23:13Z" level=debug msg="fetched chunk 5/15, size: 524288" uri="spotify:track:5QNFNjZbwfP6ah7NpDelXV" Jan 08 18:23:23 volumio volumio[1444]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 08 18:23:23 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 08 18:23:23 volumio volumio[1444]: info: Creating Spotify config file Jan 08 18:23:23 volumio volumio[1444]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 08 18:23:23 volumio volumio[1444]: info: Spotify config file written Jan 08 18:23:23 volumio sudo[22227]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 08 18:23:23 volumio sudo[22227]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 08 18:23:23 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 08 18:23:23 volumio systemd[1]: go-librespot-daemon.service: Killing process 2081 (go-librespot) with signal SIGKILL. Jan 08 18:23:23 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 08 18:23:23 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:23:23 volumio systemd[1]: go-librespot-daemon.service: Consumed 8min 29.500s CPU time. Jan 08 18:23:23 volumio volumio[1444]: info: Connection to go-librespot Websocket closed Jan 08 18:23:23 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 08 18:23:23 volumio go-librespot[22229]: go-librespot daemon starting... Jan 08 18:23:23 volumio sudo[22227]: pam_unix(sudo:session): session closed for user root Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=info msg="running go-librespot 0.4.0" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=debug msg="app state loaded" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=info msg="api server listening on 127.0.0.1:9879" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 08 18:23:23 volumio go-librespot[22230]: time="2026-01-08T18:23:23Z" level=info msg="zeroconf server listening on port 33681" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="obtained new client token: AACX2OQq3zKaxY2asGAk2CEl6Za8xfGeuKJbg7ElMWeZ9NJYWwNKuO1Q92DBTVrlyTGQMcvDc8OquiOQSkKnIm9Kl7JZe+4Sbaqej3CD9pK88KMgxQqzQzxiG+HHcQHH23faJeb0jzXl6qN3VzA8HivlH+fG35GIP+YsvnWosqUFJsF3NYjrof0GBInCtBE18ikBYaPV7WMl5pTKF0N9omcaUoTIgXkk6xHmCJRgN1mN5EBK8fJYCGA=" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="completed keyexchange" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="completed challenge" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=info msg="authenticated AP" username="mi*****92" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=info msg="authenticated Login5" username="mi*****92" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="initializing zeroconf session" username="mi*****92" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="dealer connection opened" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=trace msg="starting accesspoint recv loop" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=trace msg="starting dealer recv loop" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=trace msg="received accesspoint ping" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="received connection id: MWQ0YTljNzYtOGIw...Q0VBMEY3MUY5Mw==" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=trace msg="received accesspoint pong ack" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="put connect state because NEW_DEVICE" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="update volume requested to 65535/65535" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=debug msg="put connect state because VOLUME_CHANGED" Jan 08 18:23:24 volumio go-librespot[22230]: time="2026-01-08T18:23:24Z" level=trace msg="emitting websocket event: volume" Jan 08 18:23:26 volumio volumio[1444]: info: Initializing connection to go-librespot Websocket Jan 08 18:23:26 volumio go-librespot[22230]: time="2026-01-08T18:23:26Z" level=debug msg="new websocket client" Jan 08 18:23:26 volumio volumio[1444]: info: Connection to go-librespot Websocket established Jan 08 18:23:26 volumio volumio[1444]: info: go-librespot daemon successfully initialized Jan 08 18:23:28 volumio volumio[1444]: info: CoreCommandRouter::volumioPrevious Jan 08 18:23:28 volumio volumio[1444]: info: Spotify previous Jan 08 18:23:28 volumio volumio[1444]: info: Sending Spotify command to local API: /player/prev Jan 08 18:23:28 volumio go-librespot[22230]: panic: runtime error: invalid memory address or nil pointer dereference Jan 08 18:23:28 volumio go-librespot[22230]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x8 pc=0x9a3754] Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 32 gp=0xc00017fdc0 m=4 mp=0xc000065808 [running]: Jan 08 18:23:28 volumio go-librespot[22230]: panic({0xa52f80?, 0x10e45a0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/panic.go:779 +0x158 fp=0xc000242ac8 sp=0xc000242a18 pc=0x43fa98 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.panicmem(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/panic.go:261 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.sigpanic() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/signal_unix.go:881 +0x378 fp=0xc000242b28 sp=0xc000242ac8 pc=0x458378 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*AppPlayer).loadCurrentTrack(0xc00029e700, {0xc6e698, 0x1194a60}, 0x0, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/controls.go:255 +0x174 fp=0xc000242f40 sp=0xc000242b28 pc=0x9a3754 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*AppPlayer).skipPrev(0xc00029e700, {0xc6e698, 0x1194a60}, 0x30?) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/controls.go:516 +0x3ec fp=0xc000242fe8 sp=0xc000242f40 pc=0x9a656c Jan 08 18:23:28 volumio go-librespot[22230]: main.(*AppPlayer).handleApiRequest(0xc00029e700, {0xc6e698, 0x1194a60}, {{0xb623c0, 0x4}, {0x0, 0x0}, 0xc000282780}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/player.go:471 +0x830 fp=0xc000243268 sp=0xc000242fe8 pc=0x9af250 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*AppPlayer).Run(0xc00029e700, {0xc6e698, 0x1194a60}, 0xc00007e840) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/player.go:610 +0x94d fp=0xc000243fb0 sp=0xc000243268 pc=0x9b128d Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).withAppPlayer.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:258 +0x2c fp=0xc000243fe0 sp=0xc000243fb0 pc=0x9aa62c Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000243fe8 sp=0xc000243fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by main.(*App).withAppPlayer in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:258 +0x379 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 1 gp=0xc0000061c0 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc000045c70?, 0x2?, 0x58?, 0xed?, 0xc000045c0c?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0004cbaa0 sp=0xc0004cba80 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc0004cbc70, 0xc000045c08, 0x0?, 0x0, 0x40?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0004cbbc0 sp=0xc0004cbaa0 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc0001e0000, 0xc0004cbd80) Jan 08 18:23:28 volumio go-librespot[22230]: /src/zeroconf/zeroconf.go:283 +0x2a6 fp=0xc0004cbd08 sp=0xc0004cbbc0 pc=0x913c46 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).withAppPlayer(0xc0001ea000, {0xc6e698, 0x1194a60}, 0xc00004ba60) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:321 +0x55c fp=0xc0004cbe10 sp=0xc0004cbd08 pc=0x9a9a5c Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).withCredentials(...) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:193 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).SpotifyToken(0xc0001ea000, {0xc6e698, 0x1194a60}, {0xc000014dd0?, 0xb64373?}, {0xc0000a43c0?, 0x2697?}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:185 +0xcd fp=0xc0004cbe68 sp=0xc0004cbe10 pc=0x9a8f2d Jan 08 18:23:28 volumio go-librespot[22230]: main.main() Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:557 +0x698 fp=0xc0004cbf50 sp=0xc0004cbe68 pc=0x9aba78 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.main() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:271 +0x29d fp=0xc0004cbfe0 sp=0xc0004cbf50 pc=0x4430bd Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0004cbfe8 sp=0xc0004cbfe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 2 gp=0xc000006c40 m=nil [force gc (idle)]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005efa8 sp=0xc00005ef88 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goparkunlock(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:408 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.forcegchelper() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:326 +0xb3 fp=0xc00005efe0 sp=0xc00005efa8 pc=0x443373 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.init.6 in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:314 +0x1a Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 3 gp=0xc000007180 m=nil [GC sweep wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005f780 sp=0xc00005f760 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goparkunlock(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:408 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.bgsweep(0xc00007e000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgcsweep.go:318 +0xdf fp=0xc00005f7c8 sp=0xc00005f780 pc=0x42ea9f Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcenable.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:203 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x4233a5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcenable in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:203 +0x66 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 4 gp=0xc000007340 m=nil [GC scavenge wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x3baaf5a6?, 0x3b9aca00?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005ff78 sp=0xc00005ff58 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goparkunlock(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:408 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.(*scavengerState).park(0x1133de0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005ffa8 sp=0xc00005ff78 pc=0x42c449 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.bgscavenge(0xc00007e000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005ffc8 sp=0xc00005ffa8 pc=0x42c9f9 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcenable.gowrap2() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:204 +0x25 fp=0xc00005ffe0 sp=0xc00005ffc8 pc=0x423345 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005ffe8 sp=0xc00005ffe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcenable in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:204 +0xa5 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 5 gp=0xc000007c00 m=nil [finalizer wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc00005e648?, 0x416a85?, 0xa8?, 0x1?, 0xc0000061c0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005e620 sp=0xc00005e600 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.runfinq() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mfinal.go:194 +0x107 fp=0xc00005e7e0 sp=0xc00005e620 pc=0x4223e7 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005e7e8 sp=0xc00005e7e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.createfing in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mfinal.go:164 +0x3d Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 8 gp=0xc00017fa40 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc00004e508?, 0x0?, 0x40?, 0xfa?, 0xc00006eab0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006ea78 sp=0xc00006ea58 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0xc00006eb10?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006eab0 sp=0xc00006ea78 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c36d0, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00006ead0 sp=0xc00006eab0 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0x7?, 0x1?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006eaf8 sp=0xc00006ead0 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Accept(0xc0000d2a80) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc00006eba0 sp=0xc00006eaf8 pc=0x4e634c Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).accept(0xc0000d2a80) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc00006ec58 sp=0xc00006eba0 pc=0x54eec9 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPListener).accept(0xc00004ba20) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc00006ec80 sp=0xc00006ec58 pc=0x5650de Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPListener).Accept(0xc00004ba20) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc00006ecb0 sp=0xc00006ec80 pc=0x5642d0 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*onceCloseListener).Accept(0xc00046cbd0?) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x24 fp=0xc00006ecc8 sp=0xc00006ecb0 pc=0x7124e4 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*Server).Serve(0xc0002aa000, {0xc6d560, 0xc00004ba20}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc00006edf8 sp=0xc00006ecc8 pc=0x6f043e Jan 08 18:23:28 volumio go-librespot[22230]: net/http.Serve(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2794 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).serve(0xc0001ce630) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:630 +0x90c fp=0xc00006efc8 sp=0xc00006edf8 pc=0x99f54c Jan 08 18:23:28 volumio go-librespot[22230]: main.NewApiServer.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:296 +0x25 fp=0xc00006efe0 sp=0xc00006efc8 pc=0x99e265 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by main.NewApiServer in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:296 +0x2ff Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 47 gp=0xc00017fc00 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x1007fde419a9648?, 0x6?, 0x4?, 0x0?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0004cf298 sp=0xc0004cf278 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0004cf2d0 sp=0xc0004cf298 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c2d20, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0004cf2f0 sp=0xc0004cf2d0 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc000469600?, 0xc000472000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0004cf318 sp=0xc0004cf2f0 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc000469600, {0xc000472000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0004cf3b0 sp=0xc0004cf318 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc000469600, {0xc000472000?, 0xc0004cf470?, 0x443789?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0004cf3f8 sp=0xc0004cf3b0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0002944d8, {0xc000472000?, 0x454010?, 0x4434ee?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0004cf440 sp=0xc0004cf3f8 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc0004cf590?, {0xc000472000?, 0xc0004cf6a0?, 0x2?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0004cf470 sp=0xc0004cf440 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: io.(*multiReader).Read(0xc000013b90, {0xc000472000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0004cf4b8 sp=0xc0004cf470 pc=0x4dc913 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).fill(0xc00035b8c0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0004cf4f0 sp=0xc0004cf4b8 pc=0x672d63 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).ReadByte(0xc00035b8c0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc0004cf510 sp=0xc0004cf4f0 pc=0x673487 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.readFrameHeader(0xc00035b8c0, {0xc0002886e0, 0x8, 0x8}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0004cf5a0 sp=0xc0004cf510 pc=0x80a52b Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc000288680, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0004cf6e0 sp=0xc0004cf5a0 pc=0x80bf79 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).readLoop(0xc000288680, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc0004cf840 sp=0xc0004cf6e0 pc=0x80b8e5 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).reader(0xc000288680, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc0004cf940 sp=0xc0004cf840 pc=0x80cee5 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).Read(0xc0001ce6a8?, {0xc6e660?, 0x1194a60?}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc0004cf970 sp=0xc0004cf940 pc=0x80b25e Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).serve.func17({0xc6d770, 0xc0000dee00}, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:600 +0x3ae fp=0xc0004cfa68 sp=0xc0004cf970 pc=0x99fd4e Jan 08 18:23:28 volumio go-librespot[22230]: net/http.HandlerFunc.ServeHTTP(0xc000280000?, {0xc6d770?, 0xc0000dee00?}, 0xc000469600?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0004cfa90 sp=0xc0004cfa68 pc=0x6ec9e9 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*ServeMux).ServeHTTP(0xc000280270?, {0xc6d770, 0xc0000dee00}, 0xc0001e2b40) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc0004cfae0 sp=0xc0004cfa90 pc=0x6ee8cd Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xc6d770, 0xc0000dee00}, 0xc0001e2b40) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc0004cfb38 sp=0xc0004cfae0 pc=0x99f7a4 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.HandlerFunc.ServeHTTP(0x46ee19?, {0xc6d770?, 0xc0000dee00?}, 0xc000070b68?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0004cfb60 sp=0xc0004cfb38 pc=0x6ec9e9 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.serverHandler.ServeHTTP({0xc000383e00?}, {0xc6d770?, 0xc0000dee00?}, 0x6?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc0004cfb90 sp=0xc0004cfb60 pc=0x6f000e Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*conn).serve(0xc00046ca20, {0xc6e6d0, 0xc000296480}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc0004cffb8 sp=0xc0004cfb90 pc=0x6eb3c8 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*Server).Serve.gowrap3() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc0004cffe0 sp=0xc0004cffb8 pc=0x6f0828 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0004cffe8 sp=0xc0004cffe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*Server).Serve in goroutine 8 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 16 gp=0xc000224000 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x778a1613192da9bd?, 0x4e1f487615e0d72c?, 0xbd?, 0xa9?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0004d16d8 sp=0xc0004d16b8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0004d1710 sp=0xc0004d16d8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c35d8, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0004d1730 sp=0xc0004d1710 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc0000d2c00?, 0xc0002cc000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0004d1758 sp=0xc0004d1730 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc0000d2c00, {0xc0002cc000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0004d17f0 sp=0xc0004d1758 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc0000d2c00, {0xc0002cc000?, 0x7fde408f2038?, 0xc00041c078?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0004d1838 sp=0xc0004d17f0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0002940a0, {0xc0002cc000?, 0xc0004d1918?, 0x4166db?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0004d1880 sp=0xc0004d1838 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc0002f96c0?, {0xc0002cc000?, 0x5?, 0x70?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0004d18b0 sp=0xc0004d1880 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*atLeastReader).Read(0xc00041c078, {0xc0002cc000?, 0x0?, 0xc00041c078?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc0004d18f8 sp=0xc0004d18b0 pc=0x63285b Jan 08 18:23:28 volumio go-librespot[22230]: bytes.(*Buffer).ReadFrom(0xc0002a0630, {0xc67d60, 0xc00041c078}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc0004d1950 sp=0xc0004d18f8 pc=0x50e978 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readFromUntil(0xc0002a0388, {0xc666c0, 0xc0002940a0}, 0xc0004d1960?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc0004d1988 sp=0xc0004d1950 pc=0x632a3e Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecordOrCCS(0xc0002a0388, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc0004d1c08 sp=0xc0004d1988 pc=0x62fb4f Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecord(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).Read(0xc0002a0388, {0xc00011d000, 0x1000, 0x11?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc0004d1c78 sp=0xc0004d1c08 pc=0x6363f6 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).Read(0xc000512360, {0xc00042b7e0, 0x9, 0x71114e?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:241 +0x197 fp=0xc0004d1cb0 sp=0xc0004d1c78 pc=0x673297 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadAtLeast({0xc66a20, 0xc000512360}, {0xc00042b7e0, 0x9, 0x9}, 0x9) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:335 +0x90 fp=0xc0004d1cf8 sp=0xc0004d1cb0 pc=0x4dba30 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadFull(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:354 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.http2readFrameHeader({0xc00042b7e0, 0x9, 0x6d05d2?}, {0xc66a20?, 0xc000512360?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x65 fp=0xc0004d1d48 sp=0xc0004d1cf8 pc=0x6a8b25 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Framer).ReadFrame(0xc00042b7a0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x85 fp=0xc0004d1df0 sp=0xc0004d1d48 pc=0x6a9265 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2clientConnReadLoop).run(0xc0004d1fa8) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x12c fp=0xc0004d1f60 sp=0xc0004d1df0 pc=0x6cbf0c Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2ClientConn).readLoop(0xc000002000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x65 fp=0xc0004d1fc8 sp=0xc0004d1f60 pc=0x6cb485 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x25 fp=0xc0004d1fe0 sp=0xc0004d1fc8 pc=0x6c4425 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0004d1fe8 sp=0xc0004d1fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*http2Transport).newClientConn in goroutine 15 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xca6 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 20 gp=0xc0002e9a40 m=nil [GC worker (idle)]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x6186e6d8aac?, 0x0?, 0xd0?, 0xf?, 0x7018ee?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000060f50 sp=0xc000060f30 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcBgMarkWorker() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc000060fe0 sp=0xc000060f50 pc=0x425485 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000060fe8 sp=0xc000060fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 34 gp=0xc000482380 m=nil [GC worker (idle)]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x6186e6a749f?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005a750 sp=0xc00005a730 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcBgMarkWorker() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc00005a7e0 sp=0xc00005a750 pc=0x425485 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005a7e8 sp=0xc00005a7e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 14 gp=0xc000224540 m=nil [GC worker (idle)]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x6186e60a79b?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000061750 sp=0xc000061730 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcBgMarkWorker() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc0000617e0 sp=0xc000061750 pc=0x425485 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000617e8 sp=0xc0000617e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 21 gp=0xc0002e9c00 m=nil [GC worker (idle)]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x6186e59e5f2?, 0x0?, 0x0?, 0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000504750 sp=0xc000504730 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gcBgMarkWorker() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc0005047e0 sp=0xc000504750 pc=0x425485 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0005047e8 sp=0xc0005047e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 84 gp=0xc0002e8000 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003b9da8 sp=0xc0003b9d88 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0003b9de0 sp=0xc0003b9da8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c2c28, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0003b9e00 sp=0xc0003b9de0 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc000469680?, 0xc000037871?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0003b9e28 sp=0xc0003b9e00 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc000469680, {0xc000037871, 0x1, 0x1}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0003b9ec0 sp=0xc0003b9e28 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc000469680, {0xc000037871?, 0xc000399b44?, 0x14?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0003b9f08 sp=0xc0003b9ec0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0002944e8, {0xc000037871?, 0x0?, 0x0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0003b9f50 sp=0xc0003b9f08 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0x0?, {0xc000037871?, 0x0?, 0x0?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0003b9f80 sp=0xc0003b9f50 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*connReader).backgroundRead(0xc000037860) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:681 +0x37 fp=0xc0003b9fc8 sp=0xc0003b9f80 pc=0x6e5337 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*connReader).startBackgroundRead.gowrap2() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:677 +0x25 fp=0xc0003b9fe0 sp=0xc0003b9fc8 pc=0x6e5265 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003b9fe8 sp=0xc0003b9fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*connReader).startBackgroundRead in goroutine 114 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:677 +0xba Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 22 gp=0xc000482540 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc000073d88?, 0x41ed58?, 0xa0?, 0xe5?, 0xc0003ff9f0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000073d30 sp=0xc000073d10 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000073d68 sp=0xc000073d30 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c33e8, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000073d88 sp=0xc000073d68 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc00029e600?, 0x4166db?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000073db0 sp=0xc000073d88 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).RawRead(0xc00029e600, 0xc0003ff9f0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000073e10 sp=0xc000073db0 pc=0x4e6fca Jan 08 18:23:28 volumio go-librespot[22230]: net.(*rawConn).Read(0xc0002940e0, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000073e48 sp=0xc000073e10 pc=0x5600f6 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc00004a460, 0xc00035baa0, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc000073ea0 sp=0xc000073e48 pc=0x8b6324 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc000094380, {0xc000200000, 0x10000, 0x10000}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x4ae fp=0xc000073f48 sp=0xc000073ea0 pc=0x8b9dae Jan 08 18:23:28 volumio go-librespot[22230]: github.com/grandcat/zeroconf.(*Server).recv4(0xc0004c41e0, 0xc000094370) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xd0 fp=0xc000073fc0 sp=0xc000073f48 pc=0x90e990 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc000073fe0 sp=0xc000073fc0 pc=0x90e625 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000073fe8 sp=0xc000073fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 51 Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 23 gp=0xc000482700 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc000505520?, 0x42b91c?, 0xc0?, 0xe0?, 0xc0002ac140?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00001dce0 sp=0xc00001dcc0 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00001dd18 sp=0xc00001dce0 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c32f0, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00001dd38 sp=0xc00001dd18 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc00029e680?, 0x416845?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00001dd60 sp=0xc00001dd38 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).RawRead(0xc00029e680, 0xc0002ac140) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc00001ddc0 sp=0xc00001dd60 pc=0x4e6fca Jan 08 18:23:28 volumio go-librespot[22230]: net.(*rawConn).Read(0xc0002940f0, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc00001ddf8 sp=0xc00001ddc0 pc=0x5600f6 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc00004a480, 0xc00008a180, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc00001de50 sp=0xc00001ddf8 pc=0x8b6324 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 08 18:23:28 volumio go-librespot[22230]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc000094420, {0xc0001f0000, 0x10000, 0x10000}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x38d fp=0xc00001df48 sp=0xc00001de50 pc=0x8bd14d Jan 08 18:23:28 volumio go-librespot[22230]: github.com/grandcat/zeroconf.(*Server).recv6(0xc0004c41e0, 0xc000094410) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xd0 fp=0xc00001dfc0 sp=0xc00001df48 pc=0x90eb50 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc00001dfe0 sp=0xc00001dfc0 pc=0x90e5c5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00001dfe8 sp=0xc00001dfe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 51 Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 106 gp=0xc0002e8540 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc0000166f8?, 0x470bb7?, 0xa5?, 0x89?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000166d8 sp=0xc0000166b8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000016710 sp=0xc0000166d8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c2e18, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000016730 sp=0xc000016710 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc000469480?, 0xc0004f3000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000016758 sp=0xc000016730 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc000469480, {0xc0004f3000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0000167f0 sp=0xc000016758 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc000469480, {0xc0004f3000?, 0x7fde4199fbf8?, 0xc000426420?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc000016838 sp=0xc0000167f0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc000062058, {0xc0004f3000?, 0xc000016918?, 0x4166db?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc000016880 sp=0xc000016838 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc0000f8700?, {0xc0004f3000?, 0x5?, 0x380?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0000168b0 sp=0xc000016880 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*atLeastReader).Read(0xc000426420, {0xc0004f3000?, 0x0?, 0xc000426420?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc0000168f8 sp=0xc0000168b0 pc=0x63285b Jan 08 18:23:28 volumio go-librespot[22230]: bytes.(*Buffer).ReadFrom(0xc0005622b0, {0xc67d60, 0xc000426420}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc000016950 sp=0xc0000168f8 pc=0x50e978 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readFromUntil(0xc000562008, {0xc666c0, 0xc000062058}, 0xc000016960?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc000016988 sp=0xc000016950 pc=0x632a3e Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecordOrCCS(0xc000562008, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc000016c08 sp=0xc000016988 pc=0x62fb4f Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecord(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).Read(0xc000562008, {0xc0000ea000, 0x1000, 0x11?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc000016c78 sp=0xc000016c08 pc=0x6363f6 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).Read(0xc000398960, {0xc000324200, 0x9, 0x71114e?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:241 +0x197 fp=0xc000016cb0 sp=0xc000016c78 pc=0x673297 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadAtLeast({0xc66a20, 0xc000398960}, {0xc000324200, 0x9, 0x9}, 0x9) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:335 +0x90 fp=0xc000016cf8 sp=0xc000016cb0 pc=0x4dba30 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadFull(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:354 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.http2readFrameHeader({0xc000324200, 0x9, 0x6d05d2?}, {0xc66a20?, 0xc000398960?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x65 fp=0xc000016d48 sp=0xc000016cf8 pc=0x6a8b25 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Framer).ReadFrame(0xc0003241c0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x85 fp=0xc000016df0 sp=0xc000016d48 pc=0x6a9265 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2clientConnReadLoop).run(0xc000016fa8) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x12c fp=0xc000016f60 sp=0xc000016df0 pc=0x6cbf0c Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2ClientConn).readLoop(0xc0000e8000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x65 fp=0xc000016fc8 sp=0xc000016f60 pc=0x6cb485 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x25 fp=0xc000016fe0 sp=0xc000016fc8 pc=0x6c4425 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000016fe8 sp=0xc000016fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*http2Transport).newClientConn in goroutine 105 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xca6 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 36 gp=0xc0002e8700 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x576c2c9504456b7f?, 0x883c7f532ebb88ca?, 0x7f?, 0x6b?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000196d8 sp=0xc0000196b8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000019710 sp=0xc0000196d8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c31f8, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000019730 sp=0xc000019710 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc0000d2480?, 0xc0004da000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000019758 sp=0xc000019730 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc0000d2480, {0xc0004da000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0000197f0 sp=0xc000019758 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc0000d2480, {0xc0004da000?, 0x7fde408f2038?, 0xc00041c2d0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc000019838 sp=0xc0000197f0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0002941e8, {0xc0004da000?, 0xc000019918?, 0x4166db?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc000019880 sp=0xc000019838 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc0004fa000?, {0xc0004da000?, 0x5?, 0xe0?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0000198b0 sp=0xc000019880 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*atLeastReader).Read(0xc00041c2d0, {0xc0004da000?, 0x0?, 0xc00041c2d0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc0000198f8 sp=0xc0000198b0 pc=0x63285b Jan 08 18:23:28 volumio go-librespot[22230]: bytes.(*Buffer).ReadFrom(0xc0002a17b0, {0xc67d60, 0xc00041c2d0}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc000019950 sp=0xc0000198f8 pc=0x50e978 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readFromUntil(0xc0002a1508, {0xc666c0, 0xc0002941e8}, 0xc000019960?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc000019988 sp=0xc000019950 pc=0x632a3e Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecordOrCCS(0xc0002a1508, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc000019c08 sp=0xc000019988 pc=0x62fb4f Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecord(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).Read(0xc0002a1508, {0xc0004f2000, 0x1000, 0x11?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc000019c78 sp=0xc000019c08 pc=0x6363f6 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).Read(0xc000513aa0, {0xc00042a040, 0x9, 0x71114e?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:241 +0x197 fp=0xc000019cb0 sp=0xc000019c78 pc=0x673297 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadAtLeast({0xc66a20, 0xc000513aa0}, {0xc00042a040, 0x9, 0x9}, 0x9) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:335 +0x90 fp=0xc000019cf8 sp=0xc000019cb0 pc=0x4dba30 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadFull(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:354 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.http2readFrameHeader({0xc00042a040, 0x9, 0x6d05d2?}, {0xc66a20?, 0xc000513aa0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x65 fp=0xc000019d48 sp=0xc000019cf8 pc=0x6a8b25 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Framer).ReadFrame(0xc00042a000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x85 fp=0xc000019df0 sp=0xc000019d48 pc=0x6a9265 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2clientConnReadLoop).run(0xc000019fa8) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x12c fp=0xc000019f60 sp=0xc000019df0 pc=0x6cbf0c Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2ClientConn).readLoop(0xc0004f0000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x65 fp=0xc000019fc8 sp=0xc000019f60 pc=0x6cb485 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x25 fp=0xc000019fe0 sp=0xc000019fc8 pc=0x6c4425 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000019fe8 sp=0xc000019fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*http2Transport).newClientConn in goroutine 35 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xca6 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 31 gp=0xc0002e8a80 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc00005bf10?, 0x3?, 0x90?, 0xbd?, 0xc00005bec2?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005bd68 sp=0xc00005bd48 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc00005bf10, 0xc00005bebc, 0xc0002ac500?, 0x0, 0xc00005bd9e?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00005be88 sp=0xc00005bd68 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc0000d2e00) Jan 08 18:23:28 volumio go-librespot[22230]: /src/player/player.go:201 +0x205 fp=0xc00005bfc8 sp=0xc00005be88 pc=0x887765 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/player/player.go:183 +0x25 fp=0xc00005bfe0 sp=0xc00005bfc8 pc=0x887325 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005bfe8 sp=0xc00005bfe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/player/player.go:183 +0x1da Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 29 gp=0xc0002e8c40 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x7b36a5c9f9f7f8c7?, 0x3e142214a63fd50d?, 0xc7?, 0xf8?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00001a6d8 sp=0xc00001a6b8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00001a710 sp=0xc00001a6d8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c3008, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00001a730 sp=0xc00001a710 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc00057f700?, 0xc00030e000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00001a758 sp=0xc00001a730 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc00057f700, {0xc00030e000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc00001a7f0 sp=0xc00001a758 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc00057f700, {0xc00030e000?, 0x7fde408f2038?, 0xc00041c540?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc00001a838 sp=0xc00001a7f0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc000062000, {0xc00030e000?, 0xc00001a918?, 0x4166db?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc00001a880 sp=0xc00001a838 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc0004fa000?, {0xc00030e000?, 0x5?, 0xe0?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc00001a8b0 sp=0xc00001a880 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*atLeastReader).Read(0xc00041c540, {0xc00030e000?, 0x0?, 0xc00041c540?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc00001a8f8 sp=0xc00001a8b0 pc=0x63285b Jan 08 18:23:28 volumio go-librespot[22230]: bytes.(*Buffer).ReadFrom(0xc0002a82b0, {0xc67d60, 0xc00041c540}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc00001a950 sp=0xc00001a8f8 pc=0x50e978 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readFromUntil(0xc0002a8008, {0xc666c0, 0xc000062000}, 0xc00001a960?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc00001a988 sp=0xc00001a950 pc=0x632a3e Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecordOrCCS(0xc0002a8008, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc00001ac08 sp=0xc00001a988 pc=0x62fb4f Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecord(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).Read(0xc0002a8008, {0xc000322000, 0x1000, 0x11?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc00001ac78 sp=0xc00001ac08 pc=0x6363f6 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).Read(0xc00030ccc0, {0xc000324040, 0x9, 0x71114e?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:241 +0x197 fp=0xc00001acb0 sp=0xc00001ac78 pc=0x673297 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadAtLeast({0xc66a20, 0xc00030ccc0}, {0xc000324040, 0x9, 0x9}, 0x9) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:335 +0x90 fp=0xc00001acf8 sp=0xc00001acb0 pc=0x4dba30 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadFull(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:354 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.http2readFrameHeader({0xc000324040, 0x9, 0x6d05d2?}, {0xc66a20?, 0xc00030ccc0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x65 fp=0xc00001ad48 sp=0xc00001acf8 pc=0x6a8b25 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Framer).ReadFrame(0xc000324000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x85 fp=0xc00001adf0 sp=0xc00001ad48 pc=0x6a9265 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2clientConnReadLoop).run(0xc00001afa8) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x12c fp=0xc00001af60 sp=0xc00001adf0 pc=0x6cbf0c Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2ClientConn).readLoop(0xc000320000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x65 fp=0xc00001afc8 sp=0xc00001af60 pc=0x6cb485 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x25 fp=0xc00001afe0 sp=0xc00001afc8 pc=0x6c4425 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00001afe8 sp=0xc00001afe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*http2Transport).newClientConn in goroutine 28 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xca6 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 33 gp=0xc0004828c0 m=nil [chan receive]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc00008aba0?, 0xc000230c00?, 0x20?, 0xa4?, 0xc000504ee8?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000504e98 sp=0xc000504e78 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv(0xc000096300, 0xc000504f98, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc000504f10 sp=0xc000504e98 pc=0x40f43f Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv2(0xc000504fb0?, 0x90e990?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc000504f38 sp=0xc000504f10 pc=0x40f072 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).withAppPlayer.func1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:268 +0x72 fp=0xc000504fe0 sp=0xc000504f38 pc=0x9aa552 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000504fe8 sp=0xc000504fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by main.(*App).withAppPlayer in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:265 +0x419 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 66 gp=0xc000482a80 m=nil [chan receive]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x11961c0?, 0xc000505710?, 0x28?, 0x0?, 0x28?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000505680 sp=0xc000505660 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv(0xc0000962a0, 0xc0005057b8, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc0005056f8 sp=0xc000505680 pc=0x40f43f Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv2(0x0?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc000505720 sp=0xc0005056f8 pc=0x40f072 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*App).withAppPlayer.func2() Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:284 +0x87 fp=0xc0005057e0 sp=0xc000505720 pc=0x9aa1a7 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0005057e8 sp=0xc0005057e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by main.(*App).withAppPlayer in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/main.go:281 +0x4d9 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 67 gp=0xc000482c40 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x4166db?, 0xc0000620f0?, 0x0?, 0x0?, 0xc000505ca8?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000075c08 sp=0xc000075be8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x7fde419c87b8?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000075c40 sp=0xc000075c08 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c34e0, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000075c60 sp=0xc000075c40 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0x9?, 0x10?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000075c88 sp=0xc000075c60 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Accept(0xc00029e280) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc000075d30 sp=0xc000075c88 pc=0x4e634c Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).accept(0xc00029e280) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc000075de8 sp=0xc000075d30 pc=0x54eec9 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPListener).accept(0xc00004a3c0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc000075e10 sp=0xc000075de8 pc=0x5650de Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPListener).Accept(0xc00004a3c0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc000075e40 sp=0xc000075e10 pc=0x5642d0 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*onceCloseListener).Accept(0xc6e660?) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x24 fp=0xc000075e58 sp=0xc000075e40 pc=0x7124e4 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*Server).Serve(0xc000356000, {0xc6d560, 0xc00004a3c0}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc000075f88 sp=0xc000075e58 pc=0x6f043e Jan 08 18:23:28 volumio go-librespot[22230]: net/http.Serve(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2794 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 08 18:23:28 volumio go-librespot[22230]: /src/zeroconf/zeroconf.go:280 +0x85 fp=0xc000075fe0 sp=0xc000075f88 pc=0x913d65 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000075fe8 sp=0xc000075fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 08 18:23:28 volumio go-librespot[22230]: /src/zeroconf/zeroconf.go:280 +0x165 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 99 gp=0xc0002e8fc0 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc0003c4000?, 0x9?, 0x30?, 0x0?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000303ac8 sp=0xc000303aa8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000303b00 sp=0xc000303ac8 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c3100, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000303b20 sp=0xc000303b00 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc0004f4580?, 0xc00037c030?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000303b48 sp=0xc000303b20 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc0004f4580, {0xc00037c030, 0x3, 0x3}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc000303be0 sp=0xc000303b48 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc0004f4580, {0xc00037c030?, 0x3?, 0xb41760?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc000303c28 sp=0xc000303be0 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0002941f0, {0xc00037c030?, 0x10?, 0xc000099808?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc000303c70 sp=0xc000303c28 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0x0?, {0xc00037c030?, 0xc000303cd8?, 0x4592e9?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc000303ca0 sp=0xc000303c70 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadAtLeast({0xc666c0, 0xc0002941f0}, {0xc00037c030, 0x3, 0x3}, 0x3) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:335 +0x90 fp=0xc000303ce8 sp=0xc000303ca0 pc=0x4dba30 Jan 08 18:23:28 volumio go-librespot[22230]: io.ReadFull(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/io/io.go:354 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0xc0002834a0, {0xc6e698, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/shannon.go:124 +0x3c5 fp=0xc000303e38 sp=0xc000303ce8 pc=0x821005 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0001ea0f0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:276 +0x6c fp=0xc000303fc8 sp=0xc000303e38 pc=0x81dbcc Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:260 +0x25 fp=0xc000303fe0 sp=0xc000303fc8 pc=0x81db25 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000303fe8 sp=0xc000303fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 32 Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:260 +0x78 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 98 gp=0xc0002e9180 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc0003b8738?, 0x5?, 0x29?, 0x4?, 0xc0003b86e6?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003b8588 sp=0xc0003b8568 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc0003b8738, 0xc0003b86dc, 0x0?, 0x0, 0x0?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0003b86a8 sp=0xc0003b8588 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc0003a0000) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc0003b87c8 sp=0xc0003b86a8 pc=0x807236 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.newConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc0003b87e0 sp=0xc0003b87c8 pc=0x806da5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003b87e8 sp=0xc0003b87e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by nhooyr.io/websocket.newConn in goroutine 32 Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 100 gp=0xc000224700 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc0003b9798?, 0x2?, 0xb0?, 0x96?, 0xc0003b976c?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003b9618 sp=0xc0003b95f8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc0003b9798, 0xc0003b9768, 0x0?, 0x0, 0x0?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0003b9738 sp=0xc0003b9618 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0001ea0f0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:355 +0x7d fp=0xc0003b97c8 sp=0xc0003b9738 pc=0x81e43d Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:264 +0x25 fp=0xc0003b97e0 sp=0xc0003b97c8 pc=0x81dac5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003b97e8 sp=0xc0003b97e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 32 Jan 08 18:23:28 volumio go-librespot[22230]: /src/ap/ap.go:264 +0xff Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 101 gp=0xc0002248c0 m=nil [IO wait]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xa0e300?, 0xc0004149c0?, 0x18?, 0x57?, 0xb?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000045410 sp=0xc0000453f0 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000045448 sp=0xc000045410 pc=0x43c257 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.runtime_pollWait(0x7fde419c2f10, 0x72) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000045468 sp=0xc000045448 pc=0x470225 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).wait(0xc00057f780?, 0xc000323000?, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000045490 sp=0xc000045468 pc=0x4e0fa7 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*pollDesc).waitRead(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 08 18:23:28 volumio go-librespot[22230]: internal/poll.(*FD).Read(0xc00057f780, {0xc000323000, 0x1000, 0x1000}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc000045528 sp=0xc000045490 pc=0x4e229a Jan 08 18:23:28 volumio go-librespot[22230]: net.(*netFD).Read(0xc00057f780, {0xc000323000?, 0x7fde408f2038?, 0xc00041c660?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc000045570 sp=0xc000045528 pc=0x54cee5 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*conn).Read(0xc0000620f8, {0xc000323000?, 0xc000045650?, 0x416705?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0000455b8 sp=0xc000045570 pc=0x55c185 Jan 08 18:23:28 volumio go-librespot[22230]: net.(*TCPConn).Read(0xc000045618?, {0xc000323000?, 0x11961c0?, 0x41e44d?}) Jan 08 18:23:28 volumio go-librespot[22230]: :1 +0x25 fp=0xc0000455e8 sp=0xc0000455b8 pc=0x56db65 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*atLeastReader).Read(0xc00041c660, {0xc000323000?, 0xc0002248c0?, 0xc00041c660?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc000045630 sp=0xc0000455e8 pc=0x63285b Jan 08 18:23:28 volumio go-librespot[22230]: bytes.(*Buffer).ReadFrom(0xc0002a8d30, {0xc67d60, 0xc00041c660}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc000045688 sp=0xc000045630 pc=0x50e978 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readFromUntil(0xc0002a8a88, {0xc666c0, 0xc0000620f8}, 0xc000045698?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc0000456c0 sp=0xc000045688 pc=0x632a3e Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecordOrCCS(0xc0002a8a88, 0x0) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc000045940 sp=0xc0000456c0 pc=0x62fb4f Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).readRecord(...) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 08 18:23:28 volumio go-librespot[22230]: crypto/tls.(*Conn).Read(0xc0002a8a88, {0xc00039a000, 0x1000, 0x2?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc0000459b0 sp=0xc000045940 pc=0x6363f6 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*readWriteCloserBody).Read(0x0?, {0xc00039a000?, 0xb1c7c0?, 0x0?}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/transport.go:2429 +0x88 fp=0xc0000459e0 sp=0xc0000459b0 pc=0x706da8 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).fill(0xc000398000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc000045a18 sp=0xc0000459e0 pc=0x672d63 Jan 08 18:23:28 volumio go-librespot[22230]: bufio.(*Reader).ReadByte(0xc000398000) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc000045a38 sp=0xc000045a18 pc=0x673487 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.readFrameHeader(0xc000398000, {0xc0003a0060, 0x8, 0x8}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc000045ac8 sp=0xc000045a38 pc=0x80a52b Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc0003a0000, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc000045c08 sp=0xc000045ac8 pc=0x80bf79 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).readLoop(0xc0003a0000, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc000045d68 sp=0xc000045c08 pc=0x80b8e5 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).reader(0xc0003a0000, {0xc6e660, 0x1194a60}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc000045e68 sp=0xc000045d68 pc=0x80cee5 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).Read(0xc0003240e0?, {0xc6e660?, 0x1194a60?}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc000045e98 sp=0xc000045e68 pc=0x80b25e Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc0003240e0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:188 +0x67 fp=0xc000045fc8 sp=0xc000045e98 pc=0x8a32c7 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:125 +0x25 fp=0xc000045fe0 sp=0xc000045fc8 pc=0x8a2e45 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000045fe8 sp=0xc000045fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 32 Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:125 +0x78 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 102 gp=0xc000224a80 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc0003ba798?, 0x2?, 0x80?, 0xa6?, 0xc0003ba744?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003ba5e8 sp=0xc0003ba5c8 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc0003ba798, 0xc0003ba740, 0x3?, 0x0, 0xa62080?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0003ba708 sp=0xc0003ba5e8 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc0003240e0) Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:138 +0x8f fp=0xc0003ba7c8 sp=0xc0003ba708 pc=0x8a2f0f Jan 08 18:23:28 volumio go-librespot[22230]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Jan 08 18:23:28 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 08 18:23:28 volumio volumio[1444]: error: Failed to send command to Spotify local API: /player/prev: Error: socket hang up Jan 08 18:23:28 volumio volumio[1444]: info: Connection to go-librespot Websocket closed Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:129 +0x25 fp=0xc0003ba7e0 sp=0xc0003ba7c8 pc=0x8a2de5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003ba7e8 sp=0xc0003ba7e0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 32 Jan 08 18:23:28 volumio go-librespot[22230]: /src/dealer/dealer.go:129 +0xf6 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 49 gp=0xc000483880 m=nil [select]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0xc000507f38?, 0x5?, 0xd0?, 0x7d?, 0xc000507ee6?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000507d88 sp=0xc000507d68 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.selectgo(0xc000507f38, 0xc000507edc, 0x41303d?, 0x0, 0x414c30?, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000507ea8 sp=0xc000507d88 pc=0x454865 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc000288680) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc000507fc8 sp=0xc000507ea8 pc=0x807236 Jan 08 18:23:28 volumio go-librespot[22230]: nhooyr.io/websocket.newConn.gowrap1() Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc000507fe0 sp=0xc000507fc8 pc=0x806da5 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000507fe8 sp=0xc000507fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by nhooyr.io/websocket.newConn in goroutine 47 Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Jan 08 18:23:28 volumio go-librespot[22230]: goroutine 114 gp=0xc000483a40 m=nil [chan receive]: Jan 08 18:23:28 volumio go-librespot[22230]: runtime.gopark(0x65808?, 0xc00008aba0?, 0x20?, 0x0?, 0xc0000708a8?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000070858 sp=0xc000070838 pc=0x4434ee Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv(0xc000282780, 0xc0000709e8, 0x1) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc0000708d0 sp=0xc000070858 pc=0x40f43f Jan 08 18:23:28 volumio go-librespot[22230]: runtime.chanrecv1(0xc0000b8845?, 0x1?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/chan.go:442 +0x12 fp=0xc0000708f8 sp=0xc0000708d0 pc=0x40f052 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).handleRequest(0xc0001ce630, {{0xb623c0, 0x4}, {0x0, 0x0}, 0xc000282780}, {0xc6d770, 0xc00042a2a0}) Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:319 +0xc5 fp=0xc000070a18 sp=0xc0000708f8 pc=0x99e465 Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).serve.func9({0xc6d770?, 0xc00042a2a0?}, 0x0?) Jan 08 18:23:28 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 08 18:23:28 volumio go-librespot[22230]: /src/cmd/daemon/api_server.go:458 +0x77 fp=0xc000070a68 sp=0xc000070a18 pc=0x9a0a17 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.HandlerFunc.ServeHTTP(0xc000280000?, {0xc6d770?, 0xc00042a2a0?}, 0xc000469680?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000070a90 sp=0xc000070a68 pc=0x6ec9e9 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*ServeMux).ServeHTTP(0xc000280270?, {0xc6d770, 0xc00042a2a0}, 0xc0001e2c60) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc000070ae0 sp=0xc000070a90 pc=0x6ee8cd Jan 08 18:23:28 volumio go-librespot[22230]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xc6d770, 0xc00042a2a0}, 0xc0001e2c60) Jan 08 18:23:28 volumio go-librespot[22230]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc000070b38 sp=0xc000070ae0 pc=0x99f7a4 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.HandlerFunc.ServeHTTP(0x46ee19?, {0xc6d770?, 0xc00042a2a0?}, 0xc000070b68?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000070b60 sp=0xc000070b38 pc=0x6ec9e9 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.serverHandler.ServeHTTP({0xc000037860?}, {0xc6d770?, 0xc00042a2a0?}, 0x6?) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc000070b90 sp=0xc000070b60 pc=0x6f000e Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*conn).serve(0xc00046cbd0, {0xc6e6d0, 0xc000296480}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc000070fb8 sp=0xc000070b90 pc=0x6eb3c8 Jan 08 18:23:28 volumio go-librespot[22230]: net/http.(*Server).Serve.gowrap3() Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc000070fe0 sp=0xc000070fb8 pc=0x6f0828 Jan 08 18:23:28 volumio go-librespot[22230]: runtime.goexit({}) Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000070fe8 sp=0xc000070fe0 pc=0x4754a1 Jan 08 18:23:28 volumio go-librespot[22230]: created by net/http.(*Server).Serve in goroutine 8 Jan 08 18:23:28 volumio go-librespot[22230]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Jan 08 18:23:28 volumio go-librespot[22229]: Aborted Jan 08 18:23:29 volumio volumio[1444]: info: Getting Spotify volume Jan 08 18:23:29 volumio volumio[1444]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 18:23:29 volumio volumio[1444]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 08 18:23:29 volumio volumio[1444]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 08 18:23:29 volumio volumio[1444]: errno: -111, Jan 08 18:23:29 volumio volumio[1444]: code: 'ECONNREFUSED', Jan 08 18:23:29 volumio volumio[1444]: syscall: 'connect', Jan 08 18:23:29 volumio volumio[1444]: address: '127.0.0.1', Jan 08 18:23:29 volumio volumio[1444]: port: 9879, Jan 08 18:23:29 volumio volumio[1444]: response: undefined Jan 08 18:23:29 volumio volumio[1444]: } Jan 08 18:23:29 volumio volumio[1444]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 08 18:23:30 volumio sudo[22271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-08 18:22' Jan 08 18:23:30 volumio sudo[22271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 21:07:15 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4b5c74f40f473b90a542bf010b97924b"