Mar 28 00:53:15 lannister volumio[1106]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.19 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 28 00:53:15 lannister volumio[1106]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.19 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 28 00:53:18 lannister volumio[1106]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.19 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 00:53:18 lannister volumio[1106]: info: Received Get System Info Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:53:18 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:53:18 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:53:18 lannister volumio[1106]: info: Listing playlists Mar 28 00:53:19 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 00:53:24 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:53:24 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:53:24 lannister volumio[1106]: info: WARNING: No play method for volatile plugin undefined Mar 28 00:53:24 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:53:24 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:53:24 lannister volumio[1106]: info: WARNING: No play method for volatile plugin undefined Mar 28 00:53:25 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:53:25 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:53:25 lannister volumio[1106]: info: WARNING: No play method for volatile plugin undefined Mar 28 00:53:27 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 00:53:27 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 28 00:53:31 lannister go-librespot[3665]: time="2026-03-28T00:53:31+05:30" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Mar 28 00:53:31 lannister volumio[1106]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesSpotify Mar 28 00:53:31 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 00:53:31 lannister volumio[1106]: info: Disabling plugin spop Mar 28 00:53:31 lannister sudo[4482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop go-librespot-daemon.service Mar 28 00:53:31 lannister volumio[1106]: info: Done. Mar 28 00:53:31 lannister sudo[4482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:53:31 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:53:31 lannister systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 00:53:31 lannister systemd[1]: go-librespot-daemon.service: Killing process 3700 (go-librespot) with signal SIGKILL. Mar 28 00:53:31 lannister systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 00:53:31 lannister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:53:31 lannister systemd[1]: go-librespot-daemon.service: Consumed 1.937s CPU time. Mar 28 00:53:31 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:31 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:31 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:31 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:31 lannister sudo[4482]: pam_unix(sudo:session): session closed for user root Mar 28 00:53:33 lannister volumio[1106]: info: Enabling plugin spop Mar 28 00:53:33 lannister volumio[1106]: info: Loading plugin "spop"... Mar 28 00:53:33 lannister volumio[1106]: info: PLUGIN START: spop Mar 28 00:53:33 lannister volumio[1106]: info: Creating Spotify config file Mar 28 00:53:33 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 00:53:33 lannister volumio[1106]: info: Done. Mar 28 00:53:33 lannister volumio[1106]: info: Spotify config file written Mar 28 00:53:33 lannister volumio[1106]: info: No need to fix Spotify hosts Mar 28 00:53:33 lannister sudo[4499]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 00:53:33 lannister sudo[4499]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:53:33 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:33 lannister systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Mar 28 00:53:33 lannister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:53:33 lannister go-librespot[4501]: go-librespot daemon starting... Mar 28 00:53:33 lannister sudo[4499]: pam_unix(sudo:session): session closed for user root Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=info msg="running go-librespot 0.7.1" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=debug msg="app state loaded" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=debug msg="stored credentials not found" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=info msg="zeroconf server listening on port 38281" Mar 28 00:53:33 lannister go-librespot[4502]: time="2026-03-28T00:53:33+05:30" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 00:53:34 lannister volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 00:53:36 lannister volumio[1106]: info: go-librespot daemon successfully initialized Mar 28 00:53:39 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:53:39 lannister go-librespot[4502]: time="2026-03-28T00:53:39+05:30" level=debug msg="new websocket client" Mar 28 00:53:39 lannister volumio[1106]: info: Connection to go-librespot Websocket established Mar 28 00:53:42 lannister volumio[1106]: info: Getting Spotify volume Mar 28 00:53:42 lannister volumio[1106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 28 00:53:42 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="obtained new client token: AADAOG38dLZYdtrtno/uSkrbnjSeNmWLTfjtbKQKSRRtBOXNxV0aLpodfdo+KuvEP2T5gvUa2Y19nqwbcs1igBDnW4OlZkEBMCWdjsw+V/yBMI9dvwFMKGwxwrQKuKuy6+BMZs2cDkrjNhAXZWo3gIXe+Egh0zvJEfk/CfkiqtXzQup+/j7iFOs/vlVLY7uDQ2kPvxEGaVCspXLAeov/hui5pFILDVLtz0z/4mlO4Y/KN256+t6ROg==" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="completed keyexchange" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="completed challenge" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=info msg="authenticated AP" username="31************************ve" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=info msg="authenticated Login5" username="31************************ve" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=info msg="accepted zeroconf from 2406ERN9CI" username="31************************ve" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="dealer connection opened" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=trace msg="starting accesspoint recv loop" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=trace msg="starting dealer recv loop" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=trace msg="received accesspoint ping" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 28 00:53:44 lannister go-librespot[4502]: time="2026-03-28T00:53:44+05:30" level=debug msg="received connection id: ZTc3YjBkZGQtMDcz...OTE1NzI0QUM0Ng==" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=trace msg="received accesspoint pong ack" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="put connect state because NEW_DEVICE" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="update volume requested to 65535/65535" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="put connect state because VOLUME_CHANGED" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=trace msg="emitting websocket event: volume" Mar 28 00:53:45 lannister volumio[1106]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Mar 28 00:53:45 lannister volumio[1106]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="handling transfer player command from 135cdc7c78179a6b9463a237ab31ce33c21a18e0" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="resolved context of track" uri="spotify:station:album:69xcXWqK1aN73KgVA2i3Zq" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:station:album:69xcXWqK1aN73KgVA2i3Zq" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="loading track (paused: true, position: 3210ms)" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=trace msg="emitting websocket event: will_play" Mar 28 00:53:45 lannister volumio[1106]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:station:album:69xcXWqK1aN73KgVA2i3Zq","uri":"spotify:track:0eCajpR75pDW0r64U6hP2x","play_origin":""}} Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 310" Mar 28 00:53:45 lannister go-librespot[4502]: time="2026-03-28T00:53:45+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1402" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="selected format OGG_VORBIS_320 (c4d5fae957742dba91b95a2daf565406356d6b25)" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="requested aes key for file c4d5fae957742dba91b95a2daf565406356d6b25, gid: 0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=trace msg="found 2 cdn urls" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="fetched first chunk of 22, total size is 11468440 bytes" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=trace msg="seek to 3210ms (diff: 114ms, samples: 141561, bytes: 98296)" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="created new output device" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=info msg="loaded track \"Jo Tum Mere Ho\" (paused: true, position: 3210ms, duration: 251813ms, prefetched: false)" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="fetched chunk 2/21, size: 524288" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="fetched chunk 3/21, size: 524288" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="fetched chunk 1/21, size: 524288" uri="spotify:track:0eCajpR75pDW0r64U6hP2x" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=trace msg="emitting websocket event: metadata" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=trace msg="emitting websocket event: active" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="sending successful reply for dealer request" Mar 28 00:53:46 lannister volumio[1106]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0eCajpR75pDW0r64U6hP2x","name":"Jo Tum Mere Ho","artist_names":["Anuv Jain"],"album_name":"Jo Tum Mere Ho","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0272a77d038887cdc425f5ee55","position":3210,"duration":251813,"release_date":"year:2024 month:8 day:2","track_number":1,"disc_number":1}} Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 28 00:53:46 lannister volumio[1106]: SPOTIFY: received: {"type":"active","data":null} Mar 28 00:53:46 lannister volumio[1106]: info: Aligning Spotify Volume to Volumio Volume Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 28 00:53:46 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 28 00:53:46 lannister go-librespot[4502]: time="2026-03-28T00:53:46+05:30" level=trace msg="emitting websocket event: paused" Mar 28 00:53:46 lannister volumio[1106]: SPOTIFY: received: {"type":"paused","data":{"context_uri":"spotify:station:album:69xcXWqK1aN73KgVA2i3Zq","uri":"spotify:track:0eCajpR75pDW0r64U6hP2x","play_origin":""}} Mar 28 00:53:46 lannister volumio[1106]: SPOTIFY: PUSH STATE SPOTIFY Mar 28 00:53:46 lannister volumio[1106]: SPOTIFY: {"status":"pause","service":"spop","title":"Jo Tum Mere Ho","artist":"Anuv Jain","album":"Jo Tum Mere Ho","albumart":"https://i.scdn.co/image/ab67616d00001e0272a77d038887cdc425f5ee55","uri":"spotify:track:0eCajpR75pDW0r64U6hP2x","trackType":"spotify","seek":3210,"duration":251,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Mar 28 00:53:46 lannister volumio[1106]: info: CoreCommandRouter::servicePushState Mar 28 00:53:46 lannister volumio[1106]: info: CoreStateMachine::pushState Mar 28 00:53:46 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 28 00:53:46 lannister volumio[1106]: info: CoreCommandRouter::volumioPushState Mar 28 00:54:02 lannister volumio[1106]: info: Received OAUTH Data Mar 28 00:54:02 lannister volumio[1106]: info: Executing Spotify Oauth Login Mar 28 00:54:02 lannister volumio[1106]: info: Saving Spotify Refresh Token Mar 28 00:54:03 lannister sudo[4545]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 00:54:03 lannister sudo[4545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:03 lannister sudo[4551]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 00:54:03 lannister sudo[4551]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:03 lannister sudo[4545]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:03 lannister sudo[4551]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:03 lannister volumio[1106]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.19 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 Mar 28 00:54:03 lannister volumio[1106]: info: New Spotify access tokenBQAm7la24N... Mar 28 00:54:03 lannister volumio[1106]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 28 00:54:03 lannister volumio[1106]: SPOTIFY: User informations: {"country":"IN","display_name":"Games Space","email":"devusingh488@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31rosrr5vhihiixe4vgycyk7save"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31rosrr5vhihiixe4vgycyk7save","id":"31rosrr5vhihiixe4vgycyk7save","images":[],"product":"premium","type":"user","uri":"spotify:user:31rosrr5vhihiixe4vgycyk7save"} Mar 28 00:54:03 lannister volumio[1106]: info: Creating Spotify config file Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 00:54:03 lannister volumio[1106]: info: Spotify config file written Mar 28 00:54:03 lannister sudo[4565]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 00:54:03 lannister sudo[4565]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 00:54:03 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:03 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:03 lannister systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 00:54:03 lannister volumio[1106]: info: Listing playlists Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 00:54:03 lannister systemd[1]: go-librespot-daemon.service: Killing process 4506 (go-librespot) with signal SIGKILL. Mar 28 00:54:03 lannister systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 00:54:03 lannister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:03 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:54:03 lannister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:03 lannister go-librespot[4567]: go-librespot daemon starting... Mar 28 00:54:03 lannister sudo[4565]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=info msg="running go-librespot 0.7.1" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="app state loaded" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="stored credentials not found" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 00:54:03 lannister volumio[1106]: info: New Spotify access tokenBQCnk8AX4l... Mar 28 00:54:03 lannister volumio[1106]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=info msg="zeroconf server listening on port 45457" Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 00:54:03 lannister volumio[1106]: SPOTIFY: User informations: {"country":"IN","display_name":"Games Space","email":"devusingh488@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/31rosrr5vhihiixe4vgycyk7save"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/31rosrr5vhihiixe4vgycyk7save","id":"31rosrr5vhihiixe4vgycyk7save","images":[],"product":"premium","type":"user","uri":"spotify:user:31rosrr5vhihiixe4vgycyk7save"} Mar 28 00:54:03 lannister volumio[1106]: info: Spotify Successfully logged in Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 28 00:54:03 lannister volumio[1106]: info: [1774639443976] CoreMusicLibrary::Adding element Spotify Mar 28 00:54:03 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 00:54:03 lannister volumio[1106]: Cannot find translation for source Spotify Mar 28 00:54:03 lannister go-librespot[4568]: time="2026-03-28T00:54:03+05:30" level=debug msg="obtained new client token: AAAyHwsoeGHSVxDwO+52WRRgeWrYvikmGGwe3BB8pz0i9OK9nh+ewmVyP2yPi4Q532AihRbEWnOxb5jIzOmYvagnYOZRimtmZ8Axu8A0792i88L2i36jP1EcjrRs3zrxoQ6UxT8mIeM7uqCqrY7MhADXN4lInhs+M4Zn4BZdRvhgDQQ+gxuO3eIfz/oe/eKU0uqBrlBZs0ejhr6xayUWhs6YfvbOWBh6Hu3BerUzBeoMNgkewWM0YEGc" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="completed keyexchange" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="completed challenge" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=info msg="authenticated AP" username="31************************ve" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=info msg="authenticated Login5" username="31************************ve" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="stored credentials" username="31************************ve" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="initializing zeroconf session" username="31************************ve" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="dealer connection opened" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=trace msg="starting accesspoint recv loop" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=trace msg="starting dealer recv loop" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=trace msg="received accesspoint ping" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="received connection id: NjY3ZDA4ZDEtMWE3...OUQxQUI0NDc4OQ==" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=trace msg="received accesspoint pong ack" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="put connect state because NEW_DEVICE" Mar 28 00:54:04 lannister go-librespot[4568]: time="2026-03-28T00:54:04+05:30" level=debug msg="update volume requested to 65535/65535" Mar 28 00:54:04 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 00:54:04 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:04 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:04 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:04 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:04 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:04 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:05 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 00:54:05 lannister go-librespot[4568]: time="2026-03-28T00:54:05+05:30" level=debug msg="put connect state because VOLUME_CHANGED" Mar 28 00:54:05 lannister go-librespot[4568]: time="2026-03-28T00:54:05+05:30" level=trace msg="emitting websocket event: volume" Mar 28 00:54:06 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 00:54:06 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:06 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:06 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:06 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:06 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:06 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:06 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:54:06 lannister go-librespot[4568]: time="2026-03-28T00:54:06+05:30" level=debug msg="new websocket client" Mar 28 00:54:06 lannister volumio[1106]: info: Connection to go-librespot Websocket established Mar 28 00:54:06 lannister volumio[1106]: info: go-librespot daemon successfully initialized Mar 28 00:54:07 lannister volumio[1106]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 28 00:54:07 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 28 00:54:07 lannister volumio[1106]: info: Creating Spotify config file Mar 28 00:54:07 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 00:54:07 lannister volumio[1106]: info: Spotify config file written Mar 28 00:54:07 lannister sudo[4578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 00:54:07 lannister sudo[4578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:07 lannister systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 00:54:07 lannister systemd[1]: go-librespot-daemon.service: Killing process 4576 (go-librespot) with signal SIGKILL. Mar 28 00:54:07 lannister systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 00:54:07 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:54:07 lannister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:07 lannister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:07 lannister go-librespot[4580]: go-librespot daemon starting... Mar 28 00:54:07 lannister sudo[4578]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=info msg="running go-librespot 0.7.1" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="app state loaded" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=info msg="zeroconf server listening on port 43493" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="obtained new client token: AACqBh0B4g4IisrMKpsei3VtPG9bGNxXefOeV89mkAfamfe2IoWp5IlP8HJh7voFUCnjJjK4Aar/Nl2kO74V1FoXk9jsZAEYG79BKg64nrCncaJEREQbuhz7O1iTic6ZWUjC1PFuleA32TLNB6ch9S3L1p19frlNSpQl/TWnwh15YWyqw1ktsaq19h0oGB8tfFCO8HVSM1+qMnzPjGus9hVQXV7lx67aUsfRWdkGwPSzDQSQhLOllfNW" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="completed keyexchange" Mar 28 00:54:08 lannister go-librespot[4581]: time="2026-03-28T00:54:08+05:30" level=debug msg="completed challenge" Mar 28 00:54:08 lannister volumio[1106]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Mar 28 00:54:08 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Mar 28 00:54:08 lannister volumio[1106]: info: Creating Spotify config file Mar 28 00:54:08 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 00:54:08 lannister volumio[1106]: info: Spotify config file written Mar 28 00:54:08 lannister sudo[4591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 28 00:54:08 lannister sudo[4591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:08 lannister systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Mar 28 00:54:08 lannister systemd[1]: go-librespot-daemon.service: Killing process 4583 (go-librespot) with signal SIGKILL. Mar 28 00:54:08 lannister systemd[1]: go-librespot-daemon.service: Deactivated successfully. Mar 28 00:54:08 lannister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:08 lannister systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Mar 28 00:54:08 lannister go-librespot[4593]: go-librespot daemon starting... Mar 28 00:54:08 lannister sudo[4591]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:08 lannister go-librespot[4594]: time="2026-03-28T00:54:08+05:30" level=info msg="running go-librespot 0.7.1" Mar 28 00:54:08 lannister go-librespot[4594]: time="2026-03-28T00:54:08+05:30" level=debug msg="app state loaded" Mar 28 00:54:08 lannister go-librespot[4594]: time="2026-03-28T00:54:08+05:30" level=info msg="api server listening on 127.0.0.1:9879" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=info msg="zeroconf server listening on port 41439" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="obtained new client token: AABjr4SMiiH2XACo4C7DjQ/MpupJHYyo5SfVxv5K4eybE/ELZaUHg8dOHkVlF0/Rj4vW/8f6mJE6eLl7GV/EB8d8locegQmy8iDYo2pufKJV/VN4KjFywogyIFLtLLnsbi7Bt1dkz9WWY4ZG5U50h1lMgGG4fPwsV+yji1lYFSqUWwP/XZPWrT8W3eYkeagrm8EEKUlIkPUBKwwTSPPp4bbSk10+rrIHO8N+xrDAiSVC7eL+A8xMM21n" Mar 28 00:54:09 lannister volumio[1106]: info: Getting Spotify volume Mar 28 00:54:09 lannister volumio[1106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 28 00:54:09 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="new websocket client" Mar 28 00:54:09 lannister volumio[1106]: info: Connection to go-librespot Websocket established Mar 28 00:54:09 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="completed keyexchange" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="completed challenge" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=info msg="authenticated AP" username="31************************ve" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=info msg="authenticated Login5" username="31************************ve" Mar 28 00:54:09 lannister go-librespot[4594]: time="2026-03-28T00:54:09+05:30" level=debug msg="initializing zeroconf session" username="31************************ve" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="dealer connection opened" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=trace msg="starting accesspoint recv loop" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=trace msg="starting dealer recv loop" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=trace msg="received accesspoint ping" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 28 00:54:10 lannister volumio[1106]: info: Spotify volume: 100 Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="received connection id: ODRjMjRkNTQtZjQx...ODBCQTg5MkM0MA==" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=trace msg="received accesspoint pong ack" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="put connect state because NEW_DEVICE" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="update volume requested to 65535/65535" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="put connect state because VOLUME_CHANGED" Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=trace msg="emitting websocket event: volume" Mar 28 00:54:10 lannister volumio[1106]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Mar 28 00:54:10 lannister volumio[1106]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Mar 28 00:54:10 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:54:10 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:54:10 lannister volumio[1106]: info: Spotify Play Mar 28 00:54:10 lannister volumio[1106]: info: Sending Spotify command to local API: /player/play Mar 28 00:54:10 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/play: Error: Bad Request Mar 28 00:54:10 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:54:10 lannister go-librespot[4594]: time="2026-03-28T00:54:10+05:30" level=debug msg="new websocket client" Mar 28 00:54:10 lannister volumio[1106]: info: Connection to go-librespot Websocket established Mar 28 00:54:10 lannister volumio[1106]: info: go-librespot daemon successfully initialized Mar 28 00:54:11 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:54:11 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:54:11 lannister volumio[1106]: info: Spotify Play Mar 28 00:54:11 lannister volumio[1106]: info: Sending Spotify command to local API: /player/play Mar 28 00:54:11 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/play: Error: Bad Request Mar 28 00:54:11 lannister volumio[1106]: info: go-librespot daemon successfully initialized Mar 28 00:54:12 lannister volumio[1106]: info: Getting Spotify volume Mar 28 00:54:12 lannister volumio[1106]: info: Spotify volume: 100 Mar 28 00:54:12 lannister volumio[1106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 28 00:54:12 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:12 lannister sudo[4605]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 28 00:54:12 lannister sudo[4605]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:12 lannister sudo[4605]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:12 lannister sudo[4607]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 28 00:54:12 lannister sudo[4607]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:12 lannister sudo[4607]: pam_unix(sudo:session): session closed for user root Mar 28 00:54:13 lannister volumio[1106]: verbose: New Socket.io Connection to 192.168.1.4 from 192.168.1.19 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::volumioGetVisibleSources Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 28 00:54:13 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:13 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:13 lannister volumio[1106]: info: Listing playlists Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 28 00:54:13 lannister volumio[1106]: info: Getting Spotify volume Mar 28 00:54:13 lannister volumio[1106]: info: Spotify volume: 100 Mar 28 00:54:13 lannister volumio[1106]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 28 00:54:13 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:54:13 lannister go-librespot[4594]: time="2026-03-28T00:54:13+05:30" level=debug msg="new websocket client" Mar 28 00:54:13 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:13 lannister volumio[1106]: info: Connection to go-librespot Websocket established Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::volumioNext Mar 28 00:54:14 lannister volumio[1106]: info: CoreStateMachine::next Mar 28 00:54:14 lannister volumio[1106]: info: Spotify next Mar 28 00:54:14 lannister volumio[1106]: info: Sending Spotify command to local API: /player/next Mar 28 00:54:14 lannister go-librespot[4594]: panic: runtime error: invalid memory address or nil pointer dereference Mar 28 00:54:14 lannister go-librespot[4594]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x4 pc=0x6b5cd0] Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 9 gp=0x2242128 m=0 mp=0xffacd0 [running]: Mar 28 00:54:14 lannister go-librespot[4594]: panic({0x841d88, 0xfa0250}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x226b538 sp=0x226b4e4 pc=0xa92ec Mar 28 00:54:14 lannister go-librespot[4594]: runtime.panicmem(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/panic.go:262 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.sigpanic() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x226b568 sp=0x226b538 pc=0xabca4 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*AppPlayer).loadCurrentTrack(0x22bc460, {0xa3da8c, 0x2520c30}, 0x1, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/controls.go:308 +0x13c fp=0x226b720 sp=0x226b56c pc=0x6b5cd0 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*AppPlayer).advanceNext(0x22bc460, {0xa3da8c, 0x2520c30}, 0x1, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/controls.go:700 +0x4dc fp=0x226b7d0 sp=0x226b720 pc=0x6b9a84 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*AppPlayer).skipNext(0x22bc460, {0xa3da8c, 0x2520c30}, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/controls.go:604 +0x4e8 fp=0x226b86c sp=0x226b7d0 pc=0x6b9408 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*AppPlayer).handleApiRequest(0x22bc460, {0xa3da1c, 0x1005090}, {{0x919857, 0x4}, {0x83ac98, 0x0}, 0x2070800}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/player.go:475 +0x174 fp=0x226b9e0 sp=0x226b86c pc=0x6c2cac Mar 28 00:54:14 lannister go-librespot[4594]: main.(*AppPlayer).Run(0x22bc460, {0xa3da1c, 0x1005090}, 0x2090200, 0x2090240) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/player.go:697 +0x5b0 fp=0x226bfd4 sp=0x226b9e0 pc=0x6c5b98 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).withAppPlayer.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:274 +0x48 fp=0x226bfec sp=0x226bfd4 pc=0x6bd9f0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x226bfec sp=0x226bfec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:274 +0x46c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 1 gp=0x2002128 m=nil [select]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2269ce0 sp=0x2269ccc pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.selectgo(0x2269df0, 0x2269dbc, 0x0, 0x0, 0x2, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2269d90 sp=0x2269ce0 pc=0x85000 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x209f260, 0x2269e90) Mar 28 00:54:14 lannister go-librespot[4594]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x2269e38 sp=0x2269d90 pc=0x62f43c Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).withAppPlayer(0x223c300, {0xa3da1c, 0x1005090}, 0x20ac5a0) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x2269ed0 sp=0x2269e38 pc=0x6bcc60 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).withCredentials(...) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:209 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).SpotifyToken(0x223c300, {0xa3da1c, 0x1005090}, {0x221c640, 0x1c}, {0x221a140, 0x13e}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x2269f00 sp=0x2269ed0 pc=0x6bc02c Mar 28 00:54:14 lannister go-librespot[4594]: main.main() Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:620 +0x660 fp=0x2269fa8 sp=0x2269f00 pc=0x6bf56c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.main() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x2269fec sp=0x2269fa8 pc=0x6f390 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2269fec sp=0x2269fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2048fd4 sp=0x2048fc0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goparkunlock(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:466 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.forcegchelper() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x2048fec sp=0x2048fd4 pc=0x6f7f4 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2048fec sp=0x2048fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.init.6 in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:361 +0x1c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 3 gp=0x2002b48 m=nil [GC sweep wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20497c4 sp=0x20497b0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goparkunlock(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:466 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.bgsweep(0x202e050) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x20497e4 sp=0x20497c4 pc=0x5768c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcenable.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x20497ec sp=0x20497e4 pc=0x46c3c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20497ec sp=0x20497ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcenable in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 4 gp=0x2002c68 m=nil [GC scavenge wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2049fb4 sp=0x2049fa0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goparkunlock(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:466 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.(*scavengerState).park(0xffa170) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2049fc8 sp=0x2049fb4 pc=0x54a28 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.bgscavenge(0x202e050) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2049fe4 sp=0x2049fc8 pc=0x5516c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcenable.gowrap2() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x2049fec sp=0x2049fe4 pc=0x46be8 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2049fec sp=0x2049fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcenable in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 18 gp=0x2082248 m=nil [GOMAXPROCS updater (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20447a4 sp=0x2044790 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goparkunlock(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:466 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.updateMaxProcsGoroutine() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x20447ec sp=0x20447a4 pc=0x7f174 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20447ec sp=0x20447ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 19 gp=0x20825a8 m=nil [finalizer wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2044f88 sp=0x2044f74 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.runFinalizers() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x2044fec sp=0x2044f88 pc=0x45888 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2044fec sp=0x2044fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.createfing in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 20 gp=0x2169b08 m=nil [cleanup wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20487a4 sp=0x2048790 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goparkunlock(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:466 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x20487c4 sp=0x20487a4 pc=0x41cb0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.runCleanups() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x20487ec sp=0x20487c4 pc=0x4284c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20487ec sp=0x20487ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 36 gp=0x22430e8 m=nil [GC worker (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x977320, 0x224ea00, 0x1c, 0xa, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2272f88 sp=0x2272f74 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkWorker(0x24940c0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2272fe4 sp=0x2272f88 pc=0x49f34 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2272fec sp=0x2272fe4 pc=0x49e14 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2272fec sp=0x2272fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 37 gp=0x2243208 m=nil [GC worker (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x977320, 0x224ec00, 0x1c, 0xa, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2272788 sp=0x2272774 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkWorker(0x24940c0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x22727e4 sp=0x2272788 pc=0x49f34 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x22727ec sp=0x22727e4 pc=0x49e14 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22727ec sp=0x22727ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 21 gp=0x2169c28 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c4010, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x205bcf0 sp=0x205bcdc pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c4000, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x205bd08 sp=0x205bcf0 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c4000, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x205bd1c sp=0x205bd08 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x20a4298, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x205bd30 sp=0x205bd1c pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Accept(0x20a4280) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x205bd78 sp=0x205bd30 pc=0x13a800 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).accept(0x20a4280) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x205bde0 sp=0x205bd78 pc=0x1abc28 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*TCPListener).accept(0x20b02a0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x205be34 sp=0x205bde0 pc=0x1c3554 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*TCPListener).Accept(0x20b02a0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x205be50 sp=0x205be34 pc=0x1c259c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*onceCloseListener).Accept(0x206e0a0) Mar 28 00:54:14 lannister go-librespot[4594]: :1 +0x34 fp=0x205be68 sp=0x205be50 pc=0x367f9c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve(0x22ac008, {0xa3d218, 0x20b02a0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x205bf00 sp=0x205be68 pc=0x341154 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.Serve(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2971 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve(0x22301e0) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x205bfe4 sp=0x205bf00 pc=0x6b17bc Mar 28 00:54:14 lannister go-librespot[4594]: main.NewApiServer.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x205bfec sp=0x205bfe4 pc=0x6b04f4 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x205bfec sp=0x205bfec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by main.NewApiServer in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:332 +0x310 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 43 gp=0x2169e68 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3a10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21afcbc sp=0x21afca8 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3a00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x21afcd4 sp=0x21afcbc pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3a00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x21afce8 sp=0x21afcd4 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x235c478, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21afcfc sp=0x21afce8 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).ReadMsg(0x235c460, {0x2369fc0, 0x10, 0x10}, {0x2117918, 0x1000, 0x1000}, 0x40000000) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x21afd6c sp=0x21afcfc pc=0x1381bc Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).readMsg(0x235c460, {0x2369fc0, 0x10, 0x10}, {0x2117918, 0x1000, 0x1000}, 0x40000000) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x21afdc8 sp=0x21afd6c pc=0x1aa2c8 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*UnixConn).readMsg(0x209ddb0, {0x2369fc0, 0x10, 0x10}, {0x2117918, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x21afe28 sp=0x21afdc8 pc=0x1c9d80 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*UnixConn).ReadMsgUnix(0x209ddb0, {0x2369fc0, 0x10, 0x10}, {0x2117918, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x21afe78 sp=0x21afe28 pc=0x1c820c Mar 28 00:54:14 lannister go-librespot[4594]: github.com/godbus/dbus/v5.(*oobReader).Read(0x2117908, {0x2369fc0, 0x10, 0x10}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x21afec8 sp=0x21afe78 pc=0x4c2af0 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa39d58, 0x2117908}, {0x2369fc0, 0x10, 0x10}, 0x10) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21afef4 sp=0x21afec8 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x23567f0) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x21affa0 sp=0x21afef4 pc=0x4c32c4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x207a200) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x21affe4 sp=0x21affa0 pc=0x4aaef4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x21affec sp=0x21affe4 pc=0x4a8318 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21affec sp=0x21affec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 11 gp=0x21a0008 m=nil [chan receive]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9772f4, 0x2240138, 0xe, 0x7, 0x2) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2047740 sp=0x204772c pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv(0x2240100, 0x20477e0, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2047788 sp=0x2047740 pc=0x3414c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv2(0x2240100, 0x20477e0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x204779c sp=0x2047788 pc=0x33bf0 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).withAppPlayer.func2() Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:300 +0x6c fp=0x20477ec sp=0x204779c pc=0x6bd47c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20477ec sp=0x20477ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:297 +0x598 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 41 gp=0x21a0128 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3e10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21aeaf4 sp=0x21aeae0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3e00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x21aeb0c sp=0x21aeaf4 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3e00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x21aeb20 sp=0x21aeb0c pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x20a4518, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21aeb34 sp=0x21aeb20 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x20a4500, {0x22d6000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x21aeb7c sp=0x21aeb34 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x20a4500, {0x22d6000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x21aeba8 sp=0x21aeb7c pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x2032218, {0x22d6000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x21aebd4 sp=0x21aeba8 pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*atLeastReader).Read(0x220eaa0, {0x22d6000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x21aec00 sp=0x21aebd4 pc=0x2674c0 Mar 28 00:54:14 lannister go-librespot[4594]: bytes.(*Buffer).ReadFrom(0x22b018c, {0xa3a068, 0x220eaa0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x21aec3c sp=0x21aec00 pc=0x162058 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readFromUntil(0x22b0008, {0xa38fa8, 0x2032218}, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x21aec64 sp=0x21aec3c pc=0x26771c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecordOrCCS(0x22b0008, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x21aedc4 sp=0x21aec64 pc=0x264e40 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecord(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).Read(0x22b0008, {0x22d7000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x21aedf4 sp=0x21aedc4 pc=0x26ac04 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).Read(0x22ba6c0, {0x20ce424, 0x9, 0x9}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x21aee18 sp=0x21aedf4 pc=0x2b72a0 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa391f8, 0x22ba6c0}, {0x20ce424, 0x9, 0x9}, 0x9) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21aee44 sp=0x21aee18 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.http2readFrameHeader({0x20ce424, 0x9, 0x9}, {0xa391f8, 0x22ba6c0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x21aee6c sp=0x21aee44 pc=0x2f86ac Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Framer).ReadFrame(0x20ce400) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x21aeef4 sp=0x21aee6c pc=0x2f8e04 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2clientConnReadLoop).run(0x21aefdc) Mar 28 00:54:14 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/next: Error: socket hang up Mar 28 00:54:14 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:54:14 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:54:14 lannister volumio[1106]: info: Connection to go-librespot Websocket closed Mar 28 00:54:14 lannister systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x21aefa4 sp=0x21aeef4 pc=0x31d33c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2ClientConn).readLoop(0x21a0488) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x21aefe4 sp=0x21aefa4 pc=0x31c604 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x21aefec sp=0x21aefe4 pc=0x316960 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21aefec sp=0x21aefec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*http2Transport).newClientConn in goroutine 40 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 38 gp=0x2243448 m=nil [GC worker (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x977320, 0x224ee00, 0x1c, 0xa, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24f7f88 sp=0x24f7f74 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkWorker(0x24940c0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x24f7fe4 sp=0x24f7f88 pc=0x49f34 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x24f7fec sp=0x24f7fe4 pc=0x49e14 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24f7fec sp=0x24f7fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 39 gp=0x2243568 m=nil [GC worker (idle)]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x977320, 0x224f000, 0x1c, 0xa, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2273f88 sp=0x2273f74 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkWorker(0x24940c0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x2273fe4 sp=0x2273f88 pc=0x49f34 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gcBgMarkStartWorkers.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x2273fec sp=0x2273fe4 pc=0x49e14 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2273fec sp=0x2273fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 7 gp=0x2003208 m=nil [chan receive]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9772f4, 0x20911f8, 0xe, 0x7, 0x2) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2045f78 sp=0x2045f64 pc=0xa97c0 Mar 28 00:54:14 lannister systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv(0x20911c0, 0x0, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2045fc0 sp=0x2045f78 pc=0x3414c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv1(0x20911c0, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x2045fd4 sp=0x2045fc0 pc=0x33bcc Mar 28 00:54:14 lannister go-librespot[4594]: github.com/godbus/dbus/v5.newConn.func1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x2045fec sp=0x2045fd4 pc=0x4aa8ec Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2045fec sp=0x2045fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 10 gp=0x2242008 m=nil [chan receive]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9772f4, 0x2240178, 0xe, 0x7, 0x2) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x227173c sp=0x2271728 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv(0x2240140, 0x22717d0, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x2271784 sp=0x227173c pc=0x3414c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv2(0x2240140, 0x22717d0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x2271798 sp=0x2271784 pc=0x33bf0 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*App).withAppPlayer.func1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:284 +0x68 fp=0x22717ec sp=0x2271798 pc=0x6bd918 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22717ec sp=0x22717ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by main.(*App).withAppPlayer in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/main.go:281 +0x4f8 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 111 gp=0x2242248 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x745c2a10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2301908 sp=0x23018f4 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x745c2a00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2301920 sp=0x2301908 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x745c2a00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2301934 sp=0x2301920 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x2520ab8, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2301948 sp=0x2301934 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x2520aa0, {0x2544000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2301990 sp=0x2301948 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x2520aa0, {0x2544000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x23019bc sp=0x2301990 pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x248a300, {0x2544000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x23019e8 sp=0x23019bc pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: io.(*multiReader).Read(0x2540300, {0x2544000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x2301a14 sp=0x23019e8 pc=0xefb88 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).fill(0x2524480) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2301a38 sp=0x2301a14 pc=0x2b6c1c Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).ReadByte(0x2524480) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2301a44 sp=0x2301a38 pc=0x2b7498 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.readFrameHeader(0x2524480, {0x25421b8, 0x8, 0x8}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x2301a84 sp=0x2301a44 pc=0x372a08 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readFrameHeader(0x2542188, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x2301b08 sp=0x2301a84 pc=0x375bd4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readLoop(0x2542188, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x2301bcc sp=0x2301b08 pc=0x375390 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).reader(0x2542188, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x2301c50 sp=0x2301bcc pc=0x376798 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Read(0x2542188, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x2301c78 sp=0x2301c50 pc=0x374944 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x2301cfc sp=0x2301c78 pc=0x6b2040 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x20321a8, {0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2301d0c sp=0x2301cfc pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*ServeMux).ServeHTTP(0x207a000, {0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2301d4c sp=0x2301d0c pc=0x33f6c8 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2301d74 sp=0x2301d4c pc=0x6b19b8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x2010170, {0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2301d84 sp=0x2301d74 pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.serverHandler.ServeHTTP({0x22ac008}, {0xa3d2d8, 0x21bd6b8}, 0x21bd558) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2301da0 sp=0x2301d84 pc=0x35cb44 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*conn).serve(0x242a780, {0xa3da38, 0x20281f8}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2301fdc sp=0x2301da0 pc=0x33b894 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve.gowrap3() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2301fec sp=0x2301fdc pc=0x3415cc Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2301fec sp=0x2301fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*Server).Serve in goroutine 21 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 8 gp=0x2242368 m=nil [select]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2274674 sp=0x2274660 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.selectgo(0x2274780, 0x227474c, 0x0, 0x0, 0x3, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2274724 sp=0x2274674 pc=0x85000 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x241fdb0) Mar 28 00:54:14 lannister go-librespot[4594]: /src/player/player.go:215 +0x1f4 fp=0x22747e4 sp=0x2274724 pc=0x582954 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/player/player.go:197 +0x28 fp=0x22747ec sp=0x22747e4 pc=0x58253c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x22747ec sp=0x22747ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/player/player.go:197 +0x220 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 60 gp=0x2242488 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3210, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2265908 sp=0x22658f4 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3200, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2265920 sp=0x2265908 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3200, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2265934 sp=0x2265920 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x23a6838, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2265948 sp=0x2265934 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x23a6820, {0x24a1000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2265990 sp=0x2265948 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x23a6820, {0x24a1000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x22659bc sp=0x2265990 pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x2394328, {0x24a1000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x22659e8 sp=0x22659bc pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: io.(*multiReader).Read(0x239e750, {0x24a1000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x2265a14 sp=0x22659e8 pc=0xefb88 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).fill(0x23a3260) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2265a38 sp=0x2265a14 pc=0x2b6c1c Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).ReadByte(0x23a3260) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2265a44 sp=0x2265a38 pc=0x2b7498 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.readFrameHeader(0x23a3260, {0x210ac38, 0x8, 0x8}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x2265a84 sp=0x2265a44 pc=0x372a08 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readFrameHeader(0x210ac08, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x2265b08 sp=0x2265a84 pc=0x375bd4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readLoop(0x210ac08, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x2265bcc sp=0x2265b08 pc=0x375390 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).reader(0x210ac08, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x2265c50 sp=0x2265bcc pc=0x376798 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Read(0x210ac08, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x2265c78 sp=0x2265c50 pc=0x374944 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x2265cfc sp=0x2265c78 pc=0x6b2040 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x20321a8, {0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2265d0c sp=0x2265cfc pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*ServeMux).ServeHTTP(0x207a000, {0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2265d4c sp=0x2265d0c pc=0x33f6c8 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2265d74 sp=0x2265d4c pc=0x6b19b8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x2010170, {0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2265d84 sp=0x2265d74 pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.serverHandler.ServeHTTP({0x22ac008}, {0xa3d2d8, 0x23a09a8}, 0x23a0848) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2265da0 sp=0x2265d84 pc=0x35cb44 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*conn).serve(0x24a20c0, {0xa3da38, 0x20281f8}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2265fdc sp=0x2265da0 pc=0x33b894 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve.gowrap3() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2265fec sp=0x2265fdc pc=0x3415cc Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2265fec sp=0x2265fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*Server).Serve in goroutine 21 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 58 gp=0x21a0908 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3810, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21b0af4 sp=0x21b0ae0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3800, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x21b0b0c sp=0x21b0af4 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3800, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x21b0b20 sp=0x21b0b0c pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x22bdb98, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21b0b34 sp=0x21b0b20 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x22bdb80, {0x248e000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x21b0b7c sp=0x21b0b34 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x22bdb80, {0x248e000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x21b0ba8 sp=0x21b0b7c pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x2394210, {0x248e000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x21b0bd4 sp=0x21b0ba8 pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*atLeastReader).Read(0x239e6d0, {0x248e000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x21b0c00 sp=0x21b0bd4 pc=0x2674c0 Mar 28 00:54:14 lannister go-librespot[4594]: bytes.(*Buffer).ReadFrom(0x22b060c, {0xa3a068, 0x239e6d0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x21b0c3c sp=0x21b0c00 pc=0x162058 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readFromUntil(0x22b0488, {0xa38fa8, 0x2394210}, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x21b0c64 sp=0x21b0c3c pc=0x26771c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecordOrCCS(0x22b0488, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x21b0dc4 sp=0x21b0c64 pc=0x264e40 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecord(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).Read(0x22b0488, {0x24a0000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x21b0df4 sp=0x21b0dc4 pc=0x26ac04 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).Read(0x23a3140, {0x223c024, 0x9, 0x9}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x21b0e18 sp=0x21b0df4 pc=0x2b72a0 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa391f8, 0x23a3140}, {0x223c024, 0x9, 0x9}, 0x9) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21b0e44 sp=0x21b0e18 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.http2readFrameHeader({0x223c024, 0x9, 0x9}, {0xa391f8, 0x23a3140}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x21b0e6c sp=0x21b0e44 pc=0x2f86ac Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Framer).ReadFrame(0x223c000) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x21b0ef4 sp=0x21b0e6c pc=0x2f8e04 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2clientConnReadLoop).run(0x21b0fdc) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x21b0fa4 sp=0x21b0ef4 pc=0x31d33c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2ClientConn).readLoop(0x22426c8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x21b0fe4 sp=0x21b0fa4 pc=0x31c604 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x21b0fec sp=0x21b0fe4 pc=0x316960 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21b0fec sp=0x21b0fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*http2Transport).newClientConn in goroutine 57 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 114 gp=0x2242908 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3610, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2059d04 sp=0x2059cf0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3600, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2059d1c sp=0x2059d04 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3600, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2059d30 sp=0x2059d1c pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x241e6a8, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2059d44 sp=0x2059d30 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x241e690, {0x23ae314, 0x3, 0x3}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2059d8c sp=0x2059d44 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x241e690, {0x23ae314, 0x3, 0x3}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2059db8 sp=0x2059d8c pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x24103f0, {0x23ae314, 0x3, 0x3}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x2059de4 sp=0x2059db8 pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa38fa8, 0x24103f0}, {0x23ae314, 0x3, 0x3}, 0x3) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2059e10 sp=0x2059de4 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2399080, {0xa3da1c, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/shannon.go:124 +0x370 fp=0x2059ed4 sp=0x2059e10 pc=0x4fac0c Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2261e48) Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:289 +0x68 fp=0x2059fe4 sp=0x2059ed4 pc=0x4f74ac Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:273 +0x28 fp=0x2059fec sp=0x2059fe4 pc=0x4f7418 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2059fec sp=0x2059fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 9 Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:273 +0x98 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 31 gp=0x2242a28 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x745c3010, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24f8af4 sp=0x24f8ae0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x745c3000, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x24f8b0c sp=0x24f8af4 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x745c3000, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x24f8b20 sp=0x24f8b0c pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x24ed058, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24f8b34 sp=0x24f8b20 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x24ed040, {0x250c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x24f8b7c sp=0x24f8b34 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x24ed040, {0x250c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x24f8ba8 sp=0x24f8b7c pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x248a890, {0x250c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x24f8bd4 sp=0x24f8ba8 pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*atLeastReader).Read(0x2540080, {0x250c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x24f8c00 sp=0x24f8bd4 pc=0x2674c0 Mar 28 00:54:14 lannister go-librespot[4594]: bytes.(*Buffer).ReadFrom(0x21ee84c, {0xa3a068, 0x2540080}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x24f8c3c sp=0x24f8c00 pc=0x162058 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readFromUntil(0x21ee6c8, {0xa38fa8, 0x248a890}, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x24f8c64 sp=0x24f8c3c pc=0x26771c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecordOrCCS(0x21ee6c8, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x24f8dc4 sp=0x24f8c64 pc=0x264e40 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecord(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).Read(0x21ee6c8, {0x2528000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x24f8df4 sp=0x24f8dc4 pc=0x26ac04 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).Read(0x25242a0, {0x2532024, 0x9, 0x9}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x24f8e18 sp=0x24f8df4 pc=0x2b72a0 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa391f8, 0x25242a0}, {0x2532024, 0x9, 0x9}, 0x9) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x24f8e44 sp=0x24f8e18 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.http2readFrameHeader({0x2532024, 0x9, 0x9}, {0xa391f8, 0x25242a0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x24f8e6c sp=0x24f8e44 pc=0x2f86ac Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Framer).ReadFrame(0x2532000) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x24f8ef4 sp=0x24f8e6c pc=0x2f8e04 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2clientConnReadLoop).run(0x24f8fdc) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x24f8fa4 sp=0x24f8ef4 pc=0x31d33c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2ClientConn).readLoop(0x2526008) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x24f8fe4 sp=0x24f8fa4 pc=0x31c604 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x24f8fec sp=0x24f8fe4 pc=0x316960 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24f8fec sp=0x24f8fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*http2Transport).newClientConn in goroutine 30 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 12 gp=0x2526368 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3c10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x226edac sp=0x226ed98 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3c00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x226edc4 sp=0x226edac pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3c00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x226edd8 sp=0x226edc4 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x235c428, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x226edec sp=0x226edd8 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Accept(0x235c410) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x226ee34 sp=0x226edec pc=0x13a800 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).accept(0x235c410) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x226ee9c sp=0x226ee34 pc=0x1abc28 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*TCPListener).accept(0x2366a20) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x226eef0 sp=0x226ee9c pc=0x1c3554 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*TCPListener).Accept(0x2366a20) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x226ef0c sp=0x226eef0 pc=0x1c259c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*onceCloseListener).Accept(0x2406dc0) Mar 28 00:54:14 lannister go-librespot[4594]: :1 +0x34 fp=0x226ef24 sp=0x226ef0c pc=0x367f9c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve(0x2428378, {0xa3d218, 0x2366a20}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x226efbc sp=0x226ef24 pc=0x341154 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.Serve(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2971 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 28 00:54:14 lannister go-librespot[4594]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x226efec sp=0x226efbc pc=0x62f538 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x226efec sp=0x226efec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 28 00:54:14 lannister go-librespot[4594]: /src/zeroconf/zeroconf.go:297 +0x160 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 115 gp=0x25266c8 m=nil [select]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x226fea4 sp=0x226fe90 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.selectgo(0x226ffd4, 0x226ff78, 0x0, 0x0, 0x2, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x226ff54 sp=0x226fea4 pc=0x85000 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2261e48) Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:368 +0x8c fp=0x226ffe4 sp=0x226ff54 pc=0x4f7cf8 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:277 +0x28 fp=0x226ffec sp=0x226ffe4 pc=0x4f73c4 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x226ffec sp=0x226ffec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 9 Mar 28 00:54:14 lannister go-librespot[4594]: /src/ap/ap.go:277 +0x15c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 116 gp=0x25267e8 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x745c2e10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2197998 sp=0x2197984 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x745c2e00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x21979b0 sp=0x2197998 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x745c2e00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x21979c4 sp=0x21979b0 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x2520478, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21979d8 sp=0x21979c4 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x2520460, {0x2339000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2197a20 sp=0x21979d8 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x2520460, {0x2339000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x2197a4c sp=0x2197a20 pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x2410428, {0x2339000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x2197a78 sp=0x2197a4c pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*atLeastReader).Read(0x23573c0, {0x2339000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x2197aa4 sp=0x2197a78 pc=0x2674c0 Mar 28 00:54:14 lannister go-librespot[4594]: bytes.(*Buffer).ReadFrom(0x225714c, {0xa3a068, 0x23573c0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x2197ae0 sp=0x2197aa4 pc=0x162058 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readFromUntil(0x2256fc8, {0xa38fa8, 0x2410428}, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x2197b08 sp=0x2197ae0 pc=0x26771c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecordOrCCS(0x2256fc8, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x2197c68 sp=0x2197b08 pc=0x264e40 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecord(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).Read(0x2256fc8, {0x2351000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x2197c98 sp=0x2197c68 pc=0x26ac04 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*readWriteCloserBody).Read(0x239e000, {0x2351000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x2197cb8 sp=0x2197c98 pc=0x358048 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).fill(0x242fd10) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2197cdc sp=0x2197cb8 pc=0x2b6c1c Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).ReadByte(0x242fd10) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2197ce8 sp=0x2197cdc pc=0x2b7498 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.readFrameHeader(0x242fd10, {0x210adb8, 0x8, 0x8}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x2197d28 sp=0x2197ce8 pc=0x372a08 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readFrameHeader(0x210ad88, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x2197dac sp=0x2197d28 pc=0x375bd4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readLoop(0x210ad88, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x2197e70 sp=0x2197dac pc=0x375390 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).reader(0x210ad88, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x2197ef4 sp=0x2197e70 pc=0x376798 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Read(0x210ad88, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x2197f1c sp=0x2197ef4 pc=0x374944 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x24282c8) Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:188 +0x58 fp=0x2197fe4 sp=0x2197f1c pc=0x5a222c Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:125 +0x28 fp=0x2197fec sp=0x2197fe4 pc=0x5a3d28 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2197fec sp=0x2197fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 9 Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:125 +0x98 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 117 gp=0x2526a28 m=nil [select]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2270e84 sp=0x2270e70 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.selectgo(0x2270fd4, 0x2270f5c, 0x0, 0x0, 0x2, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x2270f34 sp=0x2270e84 pc=0x85000 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x24282c8) Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:138 +0x8c fp=0x2270fe4 sp=0x2270f34 pc=0x5a1d90 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:129 +0x28 fp=0x2270fec sp=0x2270fe4 pc=0x5a3cd4 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2270fec sp=0x2270fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 9 Mar 28 00:54:14 lannister go-librespot[4594]: /src/dealer/dealer.go:129 +0x15c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 86 gp=0x2526fc8 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x757c3410, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x21aaaf4 sp=0x21aaae0 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x757c3400, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x21aab0c sp=0x21aaaf4 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x757c3400, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x21aab20 sp=0x21aab0c pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x2249148, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21aab34 sp=0x21aab20 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x2249130, {0x225c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x21aab7c sp=0x21aab34 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x2249130, {0x225c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x21aaba8 sp=0x21aab7c pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x2394000, {0x225c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x21aabd4 sp=0x21aaba8 pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*atLeastReader).Read(0x2357510, {0x225c000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x21aac00 sp=0x21aabd4 pc=0x2674c0 Mar 28 00:54:14 lannister go-librespot[4594]: bytes.(*Buffer).ReadFrom(0x22b03cc, {0xa3a068, 0x2357510}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x21aac3c sp=0x21aac00 pc=0x162058 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readFromUntil(0x22b0248, {0xa38fa8, 0x2394000}, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x21aac64 sp=0x21aac3c pc=0x26771c Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecordOrCCS(0x22b0248, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x21aadc4 sp=0x21aac64 pc=0x264e40 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).readRecord(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:589 Mar 28 00:54:14 lannister go-librespot[4594]: crypto/tls.(*Conn).Read(0x22b0248, {0x2262000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x21aadf4 sp=0x21aadc4 pc=0x26ac04 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).Read(0x22bb3b0, {0x207a0a4, 0x9, 0x9}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x21aae18 sp=0x21aadf4 pc=0x2b72a0 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadAtLeast({0xa391f8, 0x22bb3b0}, {0x207a0a4, 0x9, 0x9}, 0x9) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21aae44 sp=0x21aae18 pc=0xee954 Mar 28 00:54:14 lannister go-librespot[4594]: io.ReadFull(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/io.go:354 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.http2readFrameHeader({0x207a0a4, 0x9, 0x9}, {0xa391f8, 0x22bb3b0}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x21aae6c sp=0x21aae44 pc=0x2f86ac Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Framer).ReadFrame(0x207a080) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x21aaef4 sp=0x21aae6c pc=0x2f8e04 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2clientConnReadLoop).run(0x21aafdc) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x21aafa4 sp=0x21aaef4 pc=0x31d33c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2ClientConn).readLoop(0x21a07e8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x21aafe4 sp=0x21aafa4 pc=0x31c604 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x21aafec sp=0x21aafe4 pc=0x316960 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x21aafec sp=0x21aafec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*http2Transport).newClientConn in goroutine 85 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 103 gp=0x25270e8 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x745c2c10, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x2267908 sp=0x22678f4 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x745c2c00, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x2267920 sp=0x2267908 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x745c2c00, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2267934 sp=0x2267920 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x2520658, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2267948 sp=0x2267934 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x2520640, {0x21f0000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2267990 sp=0x2267948 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x2520640, {0x21f0000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x22679bc sp=0x2267990 pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x248a190, {0x21f0000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x22679e8 sp=0x22679bc pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: io.(*multiReader).Read(0x2540230, {0x21f0000, 0x1000, 0x1000}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x2267a14 sp=0x22679e8 pc=0xefb88 Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).fill(0x222f140) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x2267a38 sp=0x2267a14 pc=0x2b6c1c Mar 28 00:54:14 lannister go-librespot[4594]: bufio.(*Reader).ReadByte(0x222f140) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x2267a44 sp=0x2267a38 pc=0x2b7498 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.readFrameHeader(0x222f140, {0x2542038, 0x8, 0x8}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x2267a84 sp=0x2267a44 pc=0x372a08 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readFrameHeader(0x2542008, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x2267b08 sp=0x2267a84 pc=0x375bd4 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).readLoop(0x2542008, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x2267bcc sp=0x2267b08 pc=0x375390 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).reader(0x2542008, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x2267c50 sp=0x2267bcc pc=0x376798 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Reader(...) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Mar 28 00:54:14 lannister go-librespot[4594]: github.com/coder/websocket.(*Conn).Read(0x2542008, {0xa3da00, 0x1005090}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x2267c78 sp=0x2267c50 pc=0x374944 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x2267cfc sp=0x2267c78 pc=0x6b2040 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x20321a8, {0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2267d0c sp=0x2267cfc pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*ServeMux).ServeHTTP(0x207a000, {0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x2267d4c sp=0x2267d0c pc=0x33f6c8 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x2267d74 sp=0x2267d4c pc=0x6b19b8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x2010170, {0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x2267d84 sp=0x2267d74 pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.serverHandler.ServeHTTP({0x22ac008}, {0xa3d2d8, 0x21bc8f8}, 0x21bc798) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x2267da0 sp=0x2267d84 pc=0x35cb44 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*conn).serve(0x242a600, {0xa3da38, 0x20281f8}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x2267fdc sp=0x2267da0 pc=0x33b894 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve.gowrap3() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x2267fec sp=0x2267fdc pc=0x3415cc Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x2267fec sp=0x2267fec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*Server).Serve in goroutine 21 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 113 gp=0x2003568 m=nil [chan receive]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x9772f4, 0x2070838, 0xe, 0x7, 0x2) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x24fabd8 sp=0x24fabc4 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv(0x2070800, 0x24facac, 0x1) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x24fac20 sp=0x24fabd8 pc=0x3414c Mar 28 00:54:14 lannister go-librespot[4594]: runtime.chanrecv1(0x2070800, 0x24facac) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x24fac34 sp=0x24fac20 pc=0x33bcc Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).handleRequest(0x22301e0, {{0x919857, 0x4}, {0x83ac98, 0x0}, 0x2070800}, {0xa3d2d8, 0x21bdad8}) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:355 +0x74 fp=0x24facbc sp=0x24fac34 pc=0x6b06a4 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.func8({0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /src/cmd/daemon/api_server.go:486 +0x12c fp=0x24facfc sp=0x24facbc pc=0x6b2e58 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x20320c0, {0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24fad0c sp=0x24facfc pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*ServeMux).ServeHTTP(0x207a000, {0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x24fad4c sp=0x24fad0c pc=0x33f6c8 Mar 28 00:54:14 lannister go-librespot[4594]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x24fad74 sp=0x24fad4c pc=0x6b19b8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.HandlerFunc.ServeHTTP(0x2010170, {0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x24fad84 sp=0x24fad74 pc=0x33d7d8 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.serverHandler.ServeHTTP({0x22ac008}, {0xa3d2d8, 0x21bdad8}, 0x21bd8c8) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x24fada0 sp=0x24fad84 pc=0x35cb44 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*conn).serve(0x242a7e0, {0xa3da38, 0x20281f8}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x24fafdc sp=0x24fada0 pc=0x33b894 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*Server).Serve.gowrap3() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x24fafec sp=0x24fafdc pc=0x3415cc Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x24fafec sp=0x24fafec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*Server).Serve in goroutine 21 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:3493 +0x42c Mar 28 00:54:14 lannister go-librespot[4594]: goroutine 130 gp=0x2003688 m=nil [IO wait]: Mar 28 00:54:14 lannister go-librespot[4594]: runtime.gopark(0x97745c, 0x745c2810, 0x2, 0x2, 0x5) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x20456d8 sp=0x20456c4 pc=0xa97c0 Mar 28 00:54:14 lannister go-librespot[4594]: runtime.netpollblock(0x745c2800, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x20456f0 sp=0x20456d8 pc=0x675a0 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.runtime_pollWait(0x745c2800, 0x72) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x2045704 sp=0x20456f0 pc=0xa8864 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).wait(0x2520ba8, 0x72, 0x0) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2045718 sp=0x2045704 pc=0x136044 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*pollDesc).waitRead(...) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 28 00:54:14 lannister go-librespot[4594]: internal/poll.(*FD).Read(0x2520b90, {0x2524825, 0x1, 0x1}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x2045760 sp=0x2045718 pc=0x1371c4 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*netFD).Read(0x2520b90, {0x2524825, 0x1, 0x1}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x204578c sp=0x2045760 pc=0x1a9e78 Mar 28 00:54:14 lannister go-librespot[4594]: net.(*conn).Read(0x248a3e8, {0x2524825, 0x1, 0x1}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x20457b8 sp=0x204578c pc=0x1b967c Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*connReader).backgroundRead(0x2524810) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:702 +0x3c fp=0x20457e4 sp=0x20457b8 pc=0x335ac0 Mar 28 00:54:14 lannister go-librespot[4594]: net/http.(*connReader).startBackgroundRead.gowrap2() Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:698 +0x28 fp=0x20457ec sp=0x20457e4 pc=0x3359fc Mar 28 00:54:14 lannister go-librespot[4594]: runtime.goexit({}) Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x20457ec sp=0x20457ec pc=0xb0874 Mar 28 00:54:14 lannister go-librespot[4594]: created by net/http.(*connReader).startBackgroundRead in goroutine 113 Mar 28 00:54:14 lannister go-librespot[4594]: /usr/local/go/src/net/http/server.go:698 +0xd4 Mar 28 00:54:14 lannister go-librespot[4593]: Aborted Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 00:54:14 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:14 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:14 lannister volumio[1106]: info: Initializing connection to go-librespot Websocket Mar 28 00:54:14 lannister volumio[1106]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::volumioNext Mar 28 00:54:14 lannister volumio[1106]: info: CoreStateMachine::next Mar 28 00:54:14 lannister volumio[1106]: info: Spotify next Mar 28 00:54:14 lannister volumio[1106]: info: Sending Spotify command to local API: /player/next Mar 28 00:54:14 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/next: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:14 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 28 00:54:15 lannister volumio[1106]: info: CoreCommandRouter::volumioNext Mar 28 00:54:15 lannister volumio[1106]: info: CoreStateMachine::next Mar 28 00:54:15 lannister volumio[1106]: info: Spotify next Mar 28 00:54:15 lannister volumio[1106]: info: Sending Spotify command to local API: /player/next Mar 28 00:54:15 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/next: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:15 lannister volumio[1106]: info: CoreCommandRouter::volumioNext Mar 28 00:54:15 lannister volumio[1106]: info: CoreStateMachine::next Mar 28 00:54:15 lannister volumio[1106]: info: Spotify next Mar 28 00:54:15 lannister volumio[1106]: info: Sending Spotify command to local API: /player/next Mar 28 00:54:15 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/next: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:15 lannister volumio[1106]: info: CoreCommandRouter::volumioNext Mar 28 00:54:15 lannister volumio[1106]: info: CoreStateMachine::next Mar 28 00:54:15 lannister volumio[1106]: info: Spotify next Mar 28 00:54:15 lannister volumio[1106]: info: Sending Spotify command to local API: /player/next Mar 28 00:54:15 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/next: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 28 00:54:16 lannister volumio[1106]: info: Received Get System Info Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 28 00:54:16 lannister volumio[1106]: info: Discovery: Getting this device information Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::volumioGetState Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 28 00:54:16 lannister volumio[1106]: info: CoreCommandRouter::volumioVolatilePlay Mar 28 00:54:16 lannister volumio[1106]: info: CoreStateMachine::volatilePlay Mar 28 00:54:16 lannister volumio[1106]: info: Spotify Play Mar 28 00:54:16 lannister volumio[1106]: info: Sending Spotify command to local API: /player/play Mar 28 00:54:16 lannister volumio[1106]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:17 lannister volumio[1106]: info: Getting Spotify volume Mar 28 00:54:17 lannister volumio[1106]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 00:54:17 lannister volumio[1106]: Error: connect ECONNREFUSED 127.0.0.1:9879 Mar 28 00:54:17 lannister volumio[1106]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Mar 28 00:54:17 lannister volumio[1106]: errno: -111, Mar 28 00:54:17 lannister volumio[1106]: code: 'ECONNREFUSED', Mar 28 00:54:17 lannister volumio[1106]: syscall: 'connect', Mar 28 00:54:17 lannister volumio[1106]: address: '127.0.0.1', Mar 28 00:54:17 lannister volumio[1106]: port: 9879, Mar 28 00:54:17 lannister volumio[1106]: response: undefined Mar 28 00:54:17 lannister volumio[1106]: } Mar 28 00:54:17 lannister volumio[1106]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 00:54:17 lannister sudo[4640]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 00:53' Mar 28 00:54:17 lannister sudo[4640]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 00:54:17 lannister systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Mar 28 00:54:17 lannister systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"