-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Wed 2025-07-02 22:05:27 CEST. -- Jul 02 22:04:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 22:04:01 volumio volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jul 02 22:04:01 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:01 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:03 volumio volumio[960]: info: VolumeController::SetAlsaVolume55 Jul 02 22:04:03 volumio volumio[960]: info: CoreStateMachine::pushState Jul 02 22:04:03 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:03 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:04:03 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 02 22:04:04 volumio volumio[960]: info: VolumeController::SetAlsaVolume65 Jul 02 22:04:04 volumio volumio[960]: info: CoreStateMachine::pushState Jul 02 22:04:04 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jul 02 22:04:04 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum status play Jul 02 22:04:04 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum status play Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 55 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 45 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 55 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:04 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 55 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 65 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 55 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 65 Jul 02 22:04:04 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:04 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 65 Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Jul 02 22:04:04 volumio volumio-remote-updater[552]: No test mode Jul 02 22:04:04 volumio volumio-remote-updater[552]: No alpha test mode Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:04:04 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:04 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:04:04 volumio sudo[1847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 02 22:04:04 volumio sudo[1855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 02 22:04:04 volumio sudo[1855]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:04 volumio sudo[1847]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:04 volumio volumio[960]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jul 02 22:04:04 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jul 02 22:04:04 volumio sudo[1847]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:04 volumio sudo[1855]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:04 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 02 22:04:04 volumio volumio[960]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Jul 02 22:04:04 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 02 22:04:04 volumio volumio[960]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Jul 02 22:04:05 volumio volumio[960]: SPOTIFY: SETTING SPOTIFY VOLUME 65 Jul 02 22:04:05 volumio volumio[960]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 22:04:05 volumio go-librespot[1237]: time="2025-07-02T22:04:05+02:00" level=debug msg="update volume to 42597/65535" Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 22:04:06 volumio volumio[960]: info: Received Get System Info Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:04:06 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:06 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:04:06 volumio go-librespot[1237]: time="2025-07-02T22:04:06+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 02 22:04:06 volumio go-librespot[1237]: time="2025-07-02T22:04:06+02:00" level=trace msg="emitting websocket event: volume" Jul 02 22:04:06 volumio volumio[960]: SPOTIFY: received: {"type":"volume","data":{"value":65,"max":100}} Jul 02 22:04:06 volumio volumio[960]: SPOTIFY: RECEIVED SPOTIFY VOLUME 65 Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::volumioGetBrowseSources Jul 02 22:04:06 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 22:04:08 volumio go-librespot[1237]: time="2025-07-02T22:04:08+02:00" level=trace msg="sent dealer ping" Jul 02 22:04:08 volumio go-librespot[1237]: time="2025-07-02T22:04:08+02:00" level=trace msg="received dealer pong" Jul 02 22:04:09 volumio volumio[960]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jul 02 22:04:09 volumio volumio[960]: info: Completed starting MyVolumio Plugin Jul 02 22:04:09 volumio volumio[960]: [Metrics] CommandRouter: 44s 665.09ms Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::volumiosetStartupVolume Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 02 22:04:09 volumio volumio[960]: info: VolumeController:: Setting startup Volume 15 Jul 02 22:04:09 volumio volumio[960]: info: VolumeController::SetAlsaVolume15 Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::Close All Modals sent Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::Close All Modals sent Jul 02 22:04:09 volumio volumio[960]: info: CoreStateMachine::pushState Jul 02 22:04:09 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:04:09 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jul 02 22:04:09 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum status play Jul 02 22:04:09 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 15 Jul 02 22:04:09 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 65 Jul 02 22:04:09 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 15 Jul 02 22:04:09 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:09 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 15 Jul 02 22:04:09 volumio sudo[1890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 02 22:04:09 volumio sudo[1890]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:09 volumio sudo[1890]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:09 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 02 22:04:09 volumio volumio[960]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Jul 02 22:04:09 volumio volumio[960]: info: Cannot play startup sound: Error: Command failed: /usr/bin/aplay -D volumio /volumio/app/startup.wav Jul 02 22:04:09 volumio volumio[960]: ALSA lib pcm_meter.c:677:(snd_pcm_meter_add_scope_conf) Cannot open shared library /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so ((null): /data/plugins/user_interface/peppyspectrum/alsa-lib/libpeppyalsa.so: cannot open shared object file: No such file or directory) Jul 02 22:04:09 volumio volumio[960]: aplay: main:828: audio open error: No such file or directory Jul 02 22:04:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jul 02 22:04:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jul 02 22:04:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jul 02 22:04:10 volumio volumio[960]: SPOTIFY: SETTING SPOTIFY VOLUME 15 Jul 02 22:04:10 volumio volumio[960]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 22:04:10 volumio go-librespot[1237]: time="2025-07-02T22:04:10+02:00" level=debug msg="update volume to 9830/65535" Jul 02 22:04:10 volumio ntpd[723]: Soliciting pool server 85.199.214.99 Jul 02 22:04:11 volumio go-librespot[1237]: time="2025-07-02T22:04:11+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 02 22:04:11 volumio go-librespot[1237]: time="2025-07-02T22:04:11+02:00" level=trace msg="emitting websocket event: volume" Jul 02 22:04:11 volumio volumio[960]: SPOTIFY: received: {"type":"volume","data":{"value":15,"max":100}} Jul 02 22:04:11 volumio volumio[960]: SPOTIFY: RECEIVED SPOTIFY VOLUME 15 Jul 02 22:04:11 volumio volumio[960]: info: VolumeController::SetAlsaVolume20 Jul 02 22:04:11 volumio volumio[960]: info: CoreStateMachine::pushState Jul 02 22:04:11 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:04:11 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jul 02 22:04:11 volumio ntpd[723]: Soliciting pool server 93.94.88.51 Jul 02 22:04:11 volumio ntpd[723]: Soliciting pool server 212.45.144.88 Jul 02 22:04:11 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum status play Jul 02 22:04:12 volumio ntpd[723]: Soliciting pool server 193.204.114.232 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 20 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 15 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 20 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:12 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 20 Jul 02 22:04:12 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 02 22:04:12 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:12 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:12 volumio volumio[960]: info: VolumeController::SetAlsaVolume35 Jul 02 22:04:12 volumio volumio[960]: info: CoreStateMachine::pushState Jul 02 22:04:12 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:12 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 02 22:04:12 volumio volumio[960]: info: CoreCommandRouter::volumioPushState Jul 02 22:04:12 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum status play Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 20 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 35 Jul 02 22:04:12 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:12 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 35 Jul 02 22:04:12 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 02 22:04:12 volumio volumio[960]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Jul 02 22:04:12 volumio sudo[1898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start peppyspectrum.service Jul 02 22:04:12 volumio sudo[1898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:12 volumio sudo[1898]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:12 volumio volumio[960]: info: PeppySpectrum ---peppyspectrum failed to start. Check your configuration Error: Command failed: /usr/bin/sudo /bin/systemctl start peppyspectrum.service Jul 02 22:04:12 volumio volumio[960]: Failed to start peppyspectrum.service: Unit peppyspectrum.service not found. Jul 02 22:04:12 volumio ntpd[723]: Soliciting pool server 95.231.207.178 Jul 02 22:04:12 volumio ntpd[723]: Soliciting pool server 212.45.144.3 Jul 02 22:04:12 volumio ntpd[723]: Soliciting pool server 212.45.144.206 Jul 02 22:04:13 volumio volumio[960]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Jul 02 22:04:13 volumio volumio[960]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 22:04:13 volumio go-librespot[1237]: time="2025-07-02T22:04:13+02:00" level=debug msg="update volume to 22937/65535" Jul 02 22:04:13 volumio ntpd[723]: Soliciting pool server 95.110.254.234 Jul 02 22:04:13 volumio ntpd[723]: Soliciting pool server 162.159.200.1 Jul 02 22:04:13 volumio ntpd[723]: Soliciting pool server 151.22.209.90 Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 22:04:14 volumio volumio[960]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 02 22:04:14 volumio volumio[960]: info: Received Get System Version Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 22:04:14 volumio volumio[960]: info: Received Get System Info Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:04:14 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:14 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:04:14 volumio go-librespot[1237]: time="2025-07-02T22:04:14+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 02 22:04:14 volumio go-librespot[1237]: time="2025-07-02T22:04:14+02:00" level=trace msg="emitting websocket event: volume" Jul 02 22:04:14 volumio volumio[960]: SPOTIFY: received: {"type":"volume","data":{"value":35,"max":100}} Jul 02 22:04:14 volumio volumio[960]: SPOTIFY: RECEIVED SPOTIFY VOLUME 35 Jul 02 22:04:14 volumio ntpd[723]: Soliciting pool server 2a00:6d41:200:2::13 Jul 02 22:04:14 volumio ntpd[723]: Soliciting pool server 37.247.53.178 Jul 02 22:04:14 volumio ntpd[723]: Soliciting pool server 93.94.88.50 Jul 02 22:04:14 volumio ntpd[723]: Soliciting pool server 185.19.184.35 Jul 02 22:04:15 volumio ntpd[723]: Soliciting pool server 185.157.229.254 Jul 02 22:04:15 volumio ntpd[723]: Soliciting pool server 195.32.69.63 Jul 02 22:04:15 volumio ntpd[723]: Soliciting pool server 89.46.74.148 Jul 02 22:04:16 volumio volumio[960]: info: BOOT COMPLETED Jul 02 22:04:16 volumio ntpd[723]: Soliciting pool server 162.159.200.123 Jul 02 22:04:16 volumio ntpd[723]: Soliciting pool server 129.152.16.145 Jul 02 22:04:17 volumio volumio[960]: info: CoreCommandRouter::volumioPause Jul 02 22:04:17 volumio volumio[960]: info: CoreStateMachine::pause Jul 02 22:04:17 volumio volumio[960]: info: CoreStateMachine::stPlaybackTimer Jul 02 22:04:17 volumio volumio[960]: info: CoreStateMachine::servicePause Jul 02 22:04:17 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:17 volumio volumio[960]: info: CoreCommandRouter::servicePause Jul 02 22:04:17 volumio volumio[960]: info: Spotify Received pause Jul 02 22:04:17 volumio volumio[960]: SPOTIFY: SPOTIFY PAUSE Jul 02 22:04:17 volumio volumio[960]: SPOTIFY: {"status":"play","position":0,"title":"Una Canzone D'Amore Buttata Via","artist":"Vasco Rossi","album":"Una Canzone D'Amore Buttata Via","albumart":"https://i.scdn.co/image/ab67616d0000b273f0a84abe2004f03136358316","uri":"spotify:track:3hV8uIYz5D5orhpgqixZHj","trackType":"spotify","codec":"ogg","seek":20933,"duration":271,"samplerate":"320 kbps","bitdepth":"16 bit","channels":2,"random":true,"repeat":false,"repeatSingle":false,"consume":false,"volume":35,"dbVolume":null,"disableVolumeControl":false,"mute":false,"stream":"spotify","volatile":false,"service":"spop"} Jul 02 22:04:17 volumio volumio[960]: info: Sending Spotify command to local API: /player/pause Jul 02 22:04:17 volumio go-librespot[1237]: time="2025-07-02T22:04:17+02:00" level=warning msg="failed getting output device delay" error="ALSA error at snd_pcm_delay: Input/output error" Jul 02 22:04:17 volumio go-librespot[1237]: time="2025-07-02T22:04:17+02:00" level=debug msg="pause track at 0ms" Jul 02 22:04:17 volumio go-librespot[1237]: SIGSEGV: segmentation violation Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x82 m=8 sigcode=1 addr=0x82 Jul 02 22:04:17 volumio go-librespot[1237]: signal arrived during cgo execution Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 9 gp=0x1c03d48 m=8 mp=0x1d35b88 [syscall]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.cgocall(0x5ff278, 0x1eaeecc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/cgocall.go:157 +0x50 fp=0x1eaeeb4 sp=0x1eaee9c pc=0x1d728 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/output._Cfunc_snd_pcm_close(0x1babf40) Jul 02 22:04:17 volumio go-librespot[1237]: _cgo_gotypes.go:415 +0x38 fp=0x1eaeec8 sp=0x1eaeeb4 pc=0x49bb3c Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause.func1(0x1d5ea80) Jul 02 22:04:17 volumio go-librespot[1237]: /src/output/driver-alsa.go:342 +0x50 fp=0x1eaeee4 sp=0x1eaeec8 pc=0x49f8c0 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/output.(*alsaOutput).Pause(0x1d5ea80) Jul 02 22:04:17 volumio go-librespot[1237]: /src/output/driver-alsa.go:342 +0xc0 fp=0x1eaef14 sp=0x1eaeee4 pc=0x49f7c8 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1fe83c0) Jul 02 22:04:17 volumio go-librespot[1237]: /src/player/player.go:244 +0x94c fp=0x1eaefe4 sp=0x1eaef14 pc=0x4cc328 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/player/player.go:163 +0x28 fp=0x1eaefec sp=0x1eaefe4 pc=0x4cb7b8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1eaefec sp=0x1eaefec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/player/player.go:163 +0x1c8 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 1 gp=0x1c02128 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c54cfc sp=0x1c54ce8 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1c54dfc, 0x1da5dc8, 0x0, 0x0, 0x2, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c54d9c sp=0x1c54cfc pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x1d5b920, 0x1c54e88) Jul 02 22:04:17 volumio go-librespot[1237]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x1c54e40 sp=0x1c54d9c pc=0x55dd34 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).withAppPlayer(0x1d1e840, {0x7d7634, 0xbf6000}, 0x1d0b220) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:316 +0x514 fp=0x1c54ec8 sp=0x1c54e40 pc=0x5f2dcc Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).withCredentials(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:190 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).SpotifyToken(0x1d1e840, {0x7d7634, 0xbf6000}, {0x1e20430, 0xa}, {0x1e24120, 0x11b}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:182 +0xd8 fp=0x1c54ef8 sp=0x1c54ec8 pc=0x5f22c0 Jul 02 22:04:17 volumio go-librespot[1237]: main.main() Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:618 +0x61c fp=0x1c54fa8 sp=0x1c54ef8 pc=0x5f5a58 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.main() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x1c54fec sp=0x1c54fa8 pc=0x5ca38 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c54fec sp=0x1c54fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 18 gp=0x1c84248 m=nil [force gc (idle)]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c407d4 sp=0x1c407c0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goparkunlock(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:408 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.forcegchelper() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x1c407ec sp=0x1c407d4 pc=0x5ce04 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c407ec sp=0x1c407ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.init.5 in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 19 gp=0x1c84368 m=nil [GC sweep wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c40fc4 sp=0x1c40fb0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goparkunlock(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:408 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.bgsweep(0x1c92000) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x1c40fe4 sp=0x1c40fc4 pc=0x45750 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcenable.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x1c40fec sp=0x1c40fe4 pc=0x35870 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c40fec sp=0x1c40fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcenable in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 20 gp=0x1c84488 m=nil [GC scavenge wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c417b4 sp=0x1c417a0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goparkunlock(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:408 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.(*scavengerState).park(0xbcb508) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1c417c8 sp=0x1c417b4 pc=0x42a70 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.bgscavenge(0x1c92000) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1c417e4 sp=0x1c417c8 pc=0x431a8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcenable.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x1c417ec sp=0x1c417e4 pc=0x3581c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c417ec sp=0x1c417ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcenable in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 21 gp=0x1c847e8 m=nil [finalizer wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c41f8c sp=0x1c41f78 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.runfinq() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x1c41fec sp=0x1c41f8c pc=0x3465c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c41fec sp=0x1c41fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.createfing in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 36 gp=0x1d230e8 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080f08, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1dabd28 sp=0x1dabd14 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080ef8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1dabd40 sp=0x1dabd28 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080ef8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1dabd54 sp=0x1dabd40 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1d670a8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1dabd68 sp=0x1dabd54 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Accept(0x1d67090) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1dabdb0 sp=0x1dabd68 pc=0x10eb44 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).accept(0x1d67090) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1dabe18 sp=0x1dabdb0 pc=0x17e32c Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPListener).accept(0x1d58600) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1dabe30 sp=0x1dabe18 pc=0x19642c Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPListener).Accept(0x1d58600) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1dabe4c sp=0x1dabe30 pc=0x195498 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*onceCloseListener).Accept(0x1c96a20) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x34 fp=0x1dabe64 sp=0x1dabe4c pc=0x362680 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Server).Serve(0x1cb0468, {0x7d6e18, 0x1d58600}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1dabefc sp=0x1dabe64 pc=0x340d3c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.Serve(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2794 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).serve(0x1d5a720) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x1dabfe4 sp=0x1dabefc pc=0x5e8bc0 Jul 02 22:04:17 volumio go-librespot[1237]: main.NewApiServer.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x1dabfec sp=0x1dabfe4 pc=0x5e7a90 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1dabfec sp=0x1dabfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by main.NewApiServer in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:289 +0x310 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 12 gp=0x1d23208 m=nil [chan receive]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x7366f4, 0x1d04130, 0xe, 0x7, 0x2) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c45f38 sp=0x1c45f24 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv(0x1d04100, 0x1c45fe0, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1c45f74 sp=0x1c45f38 pc=0x1ff80 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv2(0x1d04100, 0x1c45fe0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1c45f88 sp=0x1c45f74 pc=0x1fac4 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).withAppPlayer.func2() Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:280 +0x6c fp=0x1c45fec sp=0x1c45f88 pc=0x5f352c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c45fec sp=0x1c45fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by main.(*App).withAppPlayer in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:277 +0x4c4 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 13 gp=0x1d23328 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080dc8, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c465e8 sp=0x1c465d4 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080db8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c46600 sp=0x1c465e8 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080db8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c46614 sp=0x1c46600 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1c28d38, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c46628 sp=0x1c46614 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Accept(0x1c28d20) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x1c46670 sp=0x1c46628 pc=0x10eb44 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).accept(0x1c28d20) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x1c466d8 sp=0x1c46670 pc=0x17e32c Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPListener).accept(0x1ca9620) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1c466f0 sp=0x1c466d8 pc=0x19642c Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPListener).Accept(0x1ca9620) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x1c4670c sp=0x1c466f0 pc=0x195498 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*onceCloseListener).Accept(0x1c96d00) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x34 fp=0x1c46724 sp=0x1c4670c pc=0x362680 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Server).Serve(0x211b228, {0x7d6e18, 0x1ca9620}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x1c467bc sp=0x1c46724 pc=0x340d3c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.Serve(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2794 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jul 02 22:04:17 volumio go-librespot[1237]: /src/zeroconf/zeroconf.go:267 +0x8c fp=0x1c467ec sp=0x1c467bc pc=0x55de30 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c467ec sp=0x1c467ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/zeroconf/zeroconf.go:267 +0x160 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 23 gp=0x1d23448 m=nil [GC worker (idle)]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736720, 0x1caceb8, 0x1a, 0xa, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c44790 sp=0x1c4477c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcBgMarkWorker() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c447ec sp=0x1c44790 pc=0x38498 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c447ec sp=0x1c447ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 24 gp=0x1c84d88 m=nil [GC worker (idle)]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736720, 0x1caced0, 0x1a, 0xa, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c42790 sp=0x1c4277c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcBgMarkWorker() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c427ec sp=0x1c42790 pc=0x38498 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c427ec sp=0x1c427ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 25 gp=0x1c84ea8 m=nil [GC worker (idle)]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736720, 0x1cacee8, 0x1a, 0xa, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c42f90 sp=0x1c42f7c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcBgMarkWorker() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c42fec sp=0x1c42f90 pc=0x38498 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c42fec sp=0x1c42fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 26 gp=0x1c84fc8 m=nil [GC worker (idle)]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736720, 0x1cacf00, 0x1a, 0xa, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c43790 sp=0x1c4377c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gcBgMarkWorker() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x1c437ec sp=0x1c43790 pc=0x38498 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c437ec sp=0x1c437ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by runtime.gcBgMarkStartWorkers in goroutine 22 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 3 gp=0x1d22008 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080e68, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20f6ad4 sp=0x20f6ac0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080e58, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20f6aec sp=0x20f6ad4 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080e58, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20f6b00 sp=0x20f6aec pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1d67238, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f6b14 sp=0x20f6b00 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1d67220, {0x1e96000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20f6b5c sp=0x20f6b14 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1d67220, {0x1e96000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20f6b88 sp=0x20f6b5c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c94160, {0x1e96000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20f6bb4 sp=0x20f6b88 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c94160, {0x1e96000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x20f6bd4 sp=0x20f6bb4 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x210bb10, {0x1e96000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x20f6c00 sp=0x20f6bd4 pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1cb484c, {0x7d4554, 0x210bb10}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x20f6c3c sp=0x20f6c00 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1cb46c8, {0x7d373c, 0x1c94160}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x20f6c64 sp=0x20f6c3c pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1cb46c8, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x20f6dc8 sp=0x20f6c64 pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1cb46c8, {0x1fb4000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x20f6df8 sp=0x20f6dc8 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Read(0x1c9be30, {0x1d5e5a4, 0x9, 0x9}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x20f6e1c sp=0x20f6df8 pc=0x2bdf6c Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadAtLeast({0x7d3930, 0x1c9be30}, {0x1d5e5a4, 0x9, 0x9}, 0x9) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x20f6e48 sp=0x20f6e1c pc=0x104280 Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadFull(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:354 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.http2readFrameHeader({0x1d5e5a4, 0x9, 0x9}, {0x7d3930, 0x1c9be30}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20f6e70 sp=0x20f6e48 pc=0x2f6e68 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Framer).ReadFrame(0x1d5e580) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20f6eec sp=0x20f6e70 pc=0x2f7600 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2clientConnReadLoop).run(0x20f6fdc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20f6fa4 sp=0x20f6eec pc=0x31be1c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2ClientConn).readLoop(0x1d27508) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20f6fe4 sp=0x20f6fa4 pc=0x31b310 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Transport).newClientConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20f6fec sp=0x20f6fe4 pc=0x31435c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20f6fec sp=0x20f6fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*http2Transport).newClientConn in goroutine 2 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 59 gp=0x1c030e8 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080a08, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ff5940 sp=0x1ff592c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x760809f8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1ff5958 sp=0x1ff5940 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x760809f8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1ff596c sp=0x1ff5958 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1fd7738, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1ff5980 sp=0x1ff596c pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1fd7720, {0x1fec000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1ff59c8 sp=0x1ff5980 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1fd7720, {0x1fec000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1ff59f4 sp=0x1ff59c8 pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c31aa0, {0x1fec000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1ff5a20 sp=0x1ff59f4 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c31aa0, {0x1fec000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1ff5a40 sp=0x1ff5a20 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x2096b80, {0x1fec000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1ff5a6c sp=0x1ff5a40 pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1fea18c, {0x7d4554, 0x2096b80}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1ff5aa8 sp=0x1ff5a6c pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1fea008, {0x7d373c, 0x1c31aa0}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1ff5ad0 sp=0x1ff5aa8 pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1fea008, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1ff5c34 sp=0x1ff5ad0 pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1fea008, {0x1f8a000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1ff5c64 sp=0x1ff5c34 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*readWriteCloserBody).Read(0x1d0a450, {0x1f8a000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x1ff5c84 sp=0x1ff5c64 pc=0x35762c Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).fill(0x1c16d20) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1ff5ca8 sp=0x1ff5c84 pc=0x2bd8e0 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).ReadByte(0x1c16d20) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x1ff5cb4 sp=0x1ff5ca8 pc=0x2be164 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.readFrameHeader(0x1c16d20, {0x1f8e038, 0x8, 0x8}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x1ff5cf4 sp=0x1ff5cb4 pc=0x470950 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x1f8e008, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x1ff5db4 sp=0x1ff5cf4 pc=0x4735b4 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).readLoop(0x1f8e008, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x1ff5e78 sp=0x1ff5db4 pc=0x47309c Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).reader(0x1f8e008, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x1ff5f04 sp=0x1ff5e78 pc=0x4742b0 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).Reader(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).Read(0x1f8e008, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x1ff5f2c sp=0x1ff5f04 pc=0x472980 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x20b3a28) Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:188 +0x58 fp=0x1ff5fe4 sp=0x1ff5f2c pc=0x4e8b14 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:125 +0x28 fp=0x1ff5fec sp=0x1ff5fe4 pc=0x4ea5e8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ff5fec sp=0x1ff5fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:125 +0x98 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 50 gp=0x1c84908 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080d28, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20f2e84 sp=0x20f2e70 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080d18, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20f2e9c sp=0x20f2e84 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080d18, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20f2eb0 sp=0x20f2e9c pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1c28d88, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f2ec4 sp=0x20f2eb0 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).RawRead(0x1c28d70, 0x20170e0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x20f2ef0 sp=0x20f2ec4 pc=0x10f748 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*rawConn).Read(0x2105868, 0x20170e0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x20f2f10 sp=0x20f2ef0 pc=0x190854 Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x20bf6d0, 0x20170b0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x20f2f40 sp=0x20f2f10 pc=0x4fe72c Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x1d481b8, {0x1e58000, 0x10000, 0x10000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x20f2f9c sp=0x20f2f40 pc=0x502714 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/grandcat/zeroconf.(*Server).recv4(0x20c4780, 0x1d481b0) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x20f2fe0 sp=0x20f2f9c pc=0x558fa4 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x20f2fec sp=0x20f2fe0 pc=0x558c5c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20f2fec sp=0x20f2fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 27 Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 51 gp=0x1c850e8 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080c88, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1e7ce5c sp=0x1e7ce48 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080c78, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1e7ce74 sp=0x1e7ce5c pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080c78, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1e7ce88 sp=0x1e7ce74 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1c28dd8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1e7ce9c sp=0x1e7ce88 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).RawRead(0x1c28dc0, 0x1c16030) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x1e7cec8 sp=0x1e7ce9c pc=0x10f748 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*rawConn).Read(0x2105890, 0x1c16030) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x1e7cee8 sp=0x1e7cec8 pc=0x190854 Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x20bf6e0, 0x1c16000, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x1e7cf18 sp=0x1e7cee8 pc=0x4fe72c Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 02 22:04:17 volumio go-librespot[1237]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x1d481e8, {0x1e48000, 0x10000, 0x10000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x1e7cf9c sp=0x1e7cf18 pc=0x50609c Jul 02 22:04:17 volumio go-librespot[1237]: github.com/grandcat/zeroconf.(*Server).recv6(0x20c4780, 0x1d481e0) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x1e7cfe0 sp=0x1e7cf9c pc=0x559198 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x1e7cfec sp=0x1e7cfe0 pc=0x558c00 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1e7cfec sp=0x1e7cfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 27 Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 97 gp=0x1c03208 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x760808c8, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20f587c sp=0x20f5868 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x760808b8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20f5894 sp=0x20f587c pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x760808b8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20f58a8 sp=0x20f5894 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1ef60b8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f58bc sp=0x20f58a8 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1ef60a0, {0x1e77000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20f5904 sp=0x20f58bc pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1ef60a0, {0x1e77000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20f5930 sp=0x20f5904 pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1dcc000, {0x1e77000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20f595c sp=0x20f5930 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1dcc000, {0x1e77000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x20f597c sp=0x20f595c pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: io.(*multiReader).Read(0x1d0a250, {0x1e77000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x20f59a8 sp=0x20f597c pc=0x1054e8 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).fill(0x1c9a060) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x20f59cc sp=0x20f59a8 pc=0x2bd8e0 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).ReadByte(0x1c9a060) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x20f59d8 sp=0x20f59cc pc=0x2be164 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.readFrameHeader(0x1c9a060, {0x20aa178, 0x8, 0x8}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x20f5a18 sp=0x20f59d8 pc=0x470950 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x20aa148, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x20f5ad8 sp=0x20f5a18 pc=0x4735b4 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).readLoop(0x20aa148, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x20f5b9c sp=0x20f5ad8 pc=0x47309c Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).reader(0x20aa148, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x20f5c28 sp=0x20f5b9c pc=0x4742b0 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).Reader(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).Read(0x20aa148, {0x7d7614, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x20f5c50 sp=0x20f5c28 pc=0x472980 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x20f5ccc sp=0x20f5c50 pc=0x5e9490 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.HandlerFunc.ServeHTTP(0x1c940f0, {0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x20f5cdc sp=0x20f5ccc pc=0x33d320 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*ServeMux).ServeHTTP(0x1d92080, {0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x20f5d18 sp=0x20f5cdc pc=0x33f180 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x20f5d40 sp=0x20f5d18 pc=0x5e8e90 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.HandlerFunc.ServeHTTP(0x1c90120, {0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x20f5d50 sp=0x20f5d40 pc=0x33d320 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.serverHandler.ServeHTTP({0x1cb0468}, {0x7d6f4c, 0x1e40148}, 0x1e40008) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x20f5d6c sp=0x20f5d50 pc=0x340910 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*conn).serve(0x212e060, {0x7d7654, 0x1ca8168}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x20f5fdc sp=0x20f5d6c pc=0x33bb60 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Server).Serve.gowrap3() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x20f5fec sp=0x20f5fdc pc=0x3411b4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20f5fec sp=0x20f5fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Server).Serve in goroutine 36 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 10 gp=0x1c038c8 m=nil [chan receive]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x7366f4, 0x1d1ef70, 0xe, 0x7, 0x2) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ffb6b8 sp=0x1ffb6a4 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv(0x1d1ef40, 0x1ffb780, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1ffb6f4 sp=0x1ffb6b8 pc=0x1ff80 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv1(0x1d1ef40, 0x1ffb780) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1ffb708 sp=0x1ffb6f4 pc=0x1faa0 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/player.(*Player).Pause(...) Jul 02 22:04:17 volumio go-librespot[1237]: /src/player/player.go:362 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*AppPlayer).pause(0x1c28e10, {0x7d7634, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/controls.go:392 +0x1ec fp=0x1ffb798 sp=0x1ffb708 pc=0x5eee90 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*AppPlayer).handleApiRequest(0x1c28e10, {0x7d7634, 0xbf6000}, {{0x70a22f, 0x5}, {0x0, 0x0}, 0x1d1ed40}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/player.go:429 +0x940 fp=0x1ffb8f4 sp=0x1ffb798 pc=0x5f9960 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*AppPlayer).Run(0x1c28e10, {0x7d7634, 0xbf6000}, 0x1d05140) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/player.go:590 +0x6bc fp=0x1ffbfd8 sp=0x1ffb8f4 pc=0x5fb26c Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).withAppPlayer.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:254 +0x40 fp=0x1ffbfec sp=0x1ffbfd8 pc=0x5f3a44 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ffbfec sp=0x1ffbfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by main.(*App).withAppPlayer in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:254 +0x398 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 11 gp=0x1c039e8 m=nil [chan receive]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x7366f4, 0x1d04170, 0xe, 0x7, 0x2) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c45748 sp=0x1c45734 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv(0x1d04140, 0x1c457d0, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1c45784 sp=0x1c45748 pc=0x1ff80 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv2(0x1d04140, 0x1c457d0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x1c45798 sp=0x1c45784 pc=0x1fac4 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*App).withAppPlayer.func1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:264 +0x68 fp=0x1c457ec sp=0x1c45798 pc=0x5f3964 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c457ec sp=0x1c457ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by main.(*App).withAppPlayer in goroutine 1 Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/main.go:261 +0x424 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 7 gp=0x1c03b08 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080be8, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c56ad4 sp=0x1c56ac0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080bd8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c56aec sp=0x1c56ad4 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080bd8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c56b00 sp=0x1c56aec pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1dc08d8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c56b14 sp=0x1c56b00 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1dc08c0, {0x1fca000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c56b5c sp=0x1c56b14 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1dc08c0, {0x1fca000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1c56b88 sp=0x1c56b5c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c311b0, {0x1fca000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1c56bb4 sp=0x1c56b88 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c311b0, {0x1fca000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1c56bd4 sp=0x1c56bb4 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x20bfe70, {0x1fca000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1c56c00 sp=0x1c56bd4 pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1d24ccc, {0x7d4554, 0x20bfe70}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1c56c3c sp=0x1c56c00 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1d24b48, {0x7d373c, 0x1c311b0}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1c56c64 sp=0x1c56c3c pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1d24b48, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1c56dc8 sp=0x1c56c64 pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1d24b48, {0x1fcc000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1c56df8 sp=0x1c56dc8 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Read(0x1fbf200, {0x1d5e624, 0x9, 0x9}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x1c56e1c sp=0x1c56df8 pc=0x2bdf6c Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadAtLeast({0x7d3930, 0x1fbf200}, {0x1d5e624, 0x9, 0x9}, 0x9) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1c56e48 sp=0x1c56e1c pc=0x104280 Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadFull(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:354 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.http2readFrameHeader({0x1d5e624, 0x9, 0x9}, {0x7d3930, 0x1fbf200}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x1c56e70 sp=0x1c56e48 pc=0x2f6e68 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Framer).ReadFrame(0x1d5e600) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x1c56eec sp=0x1c56e70 pc=0x2f7600 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2clientConnReadLoop).run(0x1c56fdc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x1c56fa4 sp=0x1c56eec pc=0x31be1c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2ClientConn).readLoop(0x1d27608) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x1c56fe4 sp=0x1c56fa4 pc=0x31b310 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Transport).newClientConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x1c56fec sp=0x1c56fe4 pc=0x31435c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c56fec sp=0x1c56fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*http2Transport).newClientConn in goroutine 6 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 58 gp=0x1df6fc8 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1e7d6b8 sp=0x1e7d6a4 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1e7d7d4, 0x1e7d77c, 0x0, 0x0, 0x2, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1e7d758 sp=0x1e7d6b8 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2130f28) Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:355 +0x8c fp=0x1e7d7e4 sp=0x1e7d758 pc=0x4859a8 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:264 +0x28 fp=0x1e7d7ec sp=0x1e7d7e4 pc=0x4850a0 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1e7d7ec sp=0x1e7d7ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:264 +0x15c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 54 gp=0x1df70e8 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080aa8, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20f3ad4 sp=0x20f3ac0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080a98, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20f3aec sp=0x20f3ad4 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080a98, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20f3b00 sp=0x20f3aec pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1e3c888, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f3b14 sp=0x20f3b00 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1e3c870, {0x1dd7000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20f3b5c sp=0x20f3b14 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1e3c870, {0x1dd7000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20f3b88 sp=0x20f3b5c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c94150, {0x1dd7000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20f3bb4 sp=0x20f3b88 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c94150, {0x1dd7000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x20f3bd4 sp=0x20f3bb4 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x1fd46c0, {0x1dd7000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x20f3c00 sp=0x20f3bd4 pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1ee018c, {0x7d4554, 0x1fd46c0}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x20f3c3c sp=0x20f3c00 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1ee0008, {0x7d373c, 0x1c94150}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x20f3c64 sp=0x20f3c3c pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1ee0008, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x20f3dc8 sp=0x20f3c64 pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1ee0008, {0x1ef5000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x20f3df8 sp=0x20f3dc8 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Read(0x1c16cc0, {0x1cba3a4, 0x9, 0x9}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x20f3e1c sp=0x20f3df8 pc=0x2bdf6c Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadAtLeast({0x7d3930, 0x1c16cc0}, {0x1cba3a4, 0x9, 0x9}, 0x9) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x20f3e48 sp=0x20f3e1c pc=0x104280 Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadFull(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:354 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.http2readFrameHeader({0x1cba3a4, 0x9, 0x9}, {0x7d3930, 0x1c16cc0}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20f3e70 sp=0x20f3e48 pc=0x2f6e68 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Framer).ReadFrame(0x1cba380) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20f3eec sp=0x20f3e70 pc=0x2f7600 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2clientConnReadLoop).run(0x20f3fdc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20f3fa4 sp=0x20f3eec pc=0x31be1c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2ClientConn).readLoop(0x1ef2008) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20f3fe4 sp=0x20f3fa4 pc=0x31b310 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Transport).newClientConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20f3fec sp=0x20f3fe4 pc=0x31435c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20f3fec sp=0x20f3fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*http2Transport).newClientConn in goroutine 53 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 91 gp=0x1d22c68 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1daccb0 sp=0x1dacc9c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1dace14, 0x1dacd80, 0x0, 0x0, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1dacd50 sp=0x1daccb0 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x1c96440) Jul 02 22:04:17 volumio go-librespot[1237]: /src/audio/provider.go:63 +0x118 fp=0x1dacfe4 sp=0x1dacd50 pc=0x48c7fc Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/audio/provider.go:53 +0x28 fp=0x1dacfec sp=0x1dacfe4 pc=0x48d524 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1dacfec sp=0x1dacfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/audio/provider.go:53 +0x60 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 57 gp=0x1d22d88 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080b48, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c55d14 sp=0x1c55d00 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080b38, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c55d2c sp=0x1c55d14 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080b38, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c55d40 sp=0x1c55d2c pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1dc1558, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c55d54 sp=0x1c55d40 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1dc1540, {0x1e20184, 0x3, 0x3}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c55d9c sp=0x1c55d54 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1dc1540, {0x1e20184, 0x3, 0x3}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1c55dc8 sp=0x1c55d9c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c31258, {0x1e20184, 0x3, 0x3}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1c55df4 sp=0x1c55dc8 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c31258, {0x1e20184, 0x3, 0x3}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1c55e14 sp=0x1c55df4 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadAtLeast({0x7d373c, 0x1c31258}, {0x1e20184, 0x3, 0x3}, 0x3) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1c55e40 sp=0x1c55e14 pc=0x104280 Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadFull(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:354 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x1c98240, {0x7d7634, 0xbf6000}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/shannon.go:124 +0x3a0 fp=0x1c55f04 sp=0x1c55e40 pc=0x488a10 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2130f28) Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:276 +0x68 fp=0x1c55fe4 sp=0x1c55f04 pc=0x485188 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:260 +0x28 fp=0x1c55fec sp=0x1c55fe4 pc=0x4850f4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c55fec sp=0x1c55fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/ap/ap.go:260 +0x98 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 56 gp=0x1df7208 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1e78ea8 sp=0x1e78e94 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1e78fa4, 0x1e78f6c, 0x0, 0x0, 0x5, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1e78f48 sp=0x1e78ea8 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x1f8e008) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1e78fe4 sp=0x1e78f48 pc=0x46d7fc Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.newConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1e78fec sp=0x1e78fe4 pc=0x46d3f0 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1e78fec sp=0x1e78fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by nhooyr.io/websocket.newConn in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 60 gp=0x1c85328 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1da8e98 sp=0x1da8e84 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1da8fd4, 0x1da8f60, 0x0, 0x0, 0x2, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1da8f38 sp=0x1da8e98 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x20b3a28) Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:138 +0x8c fp=0x1da8fe4 sp=0x1da8f38 pc=0x4e868c Jul 02 22:04:17 volumio go-librespot[1237]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:129 +0x28 fp=0x1da8fec sp=0x1da8fe4 pc=0x4ea594 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1da8fec sp=0x1da8fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 10 Jul 02 22:04:17 volumio go-librespot[1237]: /src/dealer/dealer.go:129 +0x15c Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 49 gp=0x1c85448 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c5beb0 sp=0x1c5be9c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1c5bfac, 0x1c5bf78, 0x0, 0x0, 0x2, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1c5bf50 sp=0x1c5beb0 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).writeLoop(0x1cda0b8) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x1c5bfe4 sp=0x1c5bf50 pc=0x357748 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Transport).dialConn.gowrap3() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x1c5bfec sp=0x1c5bfe4 pc=0x354554 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c5bfec sp=0x1c5bfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Transport).dialConn in goroutine 43 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 142 gp=0x1c85568 m=nil [chan receive]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x7366f4, 0x1d1ed70, 0xe, 0x7, 0x2) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1eb1bcc sp=0x1eb1bb8 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv(0x1d1ed40, 0x1eb1c98, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x1eb1c08 sp=0x1eb1bcc pc=0x1ff80 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.chanrecv1(0x1d1ed40, 0x1eb1c98) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x1eb1c1c sp=0x1eb1c08 pc=0x1faa0 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).handleRequest(0x1d5a720, {{0x70a22f, 0x5}, {0x0, 0x0}, 0x1d1ed40}, {0x7d6f4c, 0x1e41868}) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:312 +0x74 fp=0x1eb1ca8 sp=0x1eb1c1c pc=0x5e7c40 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).serve.func6({0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /src/cmd/daemon/api_server.go:408 +0x9c fp=0x1eb1ccc sp=0x1eb1ca8 pc=0x5ea7cc Jul 02 22:04:17 volumio go-librespot[1237]: net/http.HandlerFunc.ServeHTTP(0x1c300e8, {0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1eb1cdc sp=0x1eb1ccc pc=0x33d320 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*ServeMux).ServeHTTP(0x1d92080, {0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x1eb1d18 sp=0x1eb1cdc pc=0x33f180 Jul 02 22:04:17 volumio go-librespot[1237]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x1eb1d40 sp=0x1eb1d18 pc=0x5e8e90 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.HandlerFunc.ServeHTTP(0x1c90120, {0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x1eb1d50 sp=0x1eb1d40 pc=0x33d320 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.serverHandler.ServeHTTP({0x1cb0468}, {0x7d6f4c, 0x1e41868}, 0x1e41728) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x1eb1d6c sp=0x1eb1d50 pc=0x340910 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*conn).serve(0x1e34780, {0x7d7654, 0x1ca8168}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x1eb1fdc sp=0x1eb1d6c pc=0x33bb60 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Server).Serve.gowrap3() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x1eb1fec sp=0x1eb1fdc pc=0x3411b4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1eb1fec sp=0x1eb1fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Server).Serve in goroutine 36 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 78 gp=0x205fe68 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080968, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20f4ad4 sp=0x20f4ac0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080958, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x20f4aec sp=0x20f4ad4 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080958, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20f4b00 sp=0x20f4aec pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1e3c798, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20f4b14 sp=0x20f4b00 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1e3c780, {0x2001000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x20f4b5c sp=0x20f4b14 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1e3c780, {0x2001000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x20f4b88 sp=0x20f4b5c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1d20000, {0x2001000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x20f4bb4 sp=0x20f4b88 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1d20000, {0x2001000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x20f4bd4 sp=0x20f4bb4 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x1f4aa20, {0x2001000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x20f4c00 sp=0x20f4bd4 pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1d96ccc, {0x7d4554, 0x1f4aa20}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x20f4c3c sp=0x20f4c00 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1d96b48, {0x7d373c, 0x1d20000}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x20f4c64 sp=0x20f4c3c pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1d96b48, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x20f4dc8 sp=0x20f4c64 pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1d96b48, {0x1dd6000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x20f4df8 sp=0x20f4dc8 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Read(0x1dfa090, {0x1d92024, 0x9, 0x9}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x20f4e1c sp=0x20f4df8 pc=0x2bdf6c Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadAtLeast({0x7d3930, 0x1dfa090}, {0x1d92024, 0x9, 0x9}, 0x9) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x20f4e48 sp=0x20f4e1c pc=0x104280 Jul 02 22:04:17 volumio go-librespot[1237]: io.ReadFull(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/io/io.go:354 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.http2readFrameHeader({0x1d92024, 0x9, 0x9}, {0x7d3930, 0x1dfa090}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x20f4e70 sp=0x20f4e48 pc=0x2f6e68 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Framer).ReadFrame(0x1d92000) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x20f4eec sp=0x20f4e70 pc=0x2f7600 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2clientConnReadLoop).run(0x20f4fdc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x20f4fa4 sp=0x20f4eec pc=0x31be1c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2ClientConn).readLoop(0x1cd2208) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x20f4fe4 sp=0x20f4fa4 pc=0x31b310 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*http2Transport).newClientConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x20f4fec sp=0x20f4fe4 pc=0x31435c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20f4fec sp=0x20f4fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*http2Transport).newClientConn in goroutine 77 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 116 gp=0x1c84c68 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1e79ea8 sp=0x1e79e94 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1e79fa4, 0x1e79f6c, 0x0, 0x0, 0x5, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1e79f48 sp=0x1e79ea8 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x20aa148) Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x1e79fe4 sp=0x1e79f48 pc=0x46d7fc Jul 02 22:04:17 volumio go-librespot[1237]: nhooyr.io/websocket.newConn.gowrap1() Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x1e79fec sp=0x1e79fe4 pc=0x46d3f0 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1e79fec sp=0x1e79fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by nhooyr.io/websocket.newConn in goroutine 97 Jul 02 22:04:17 volumio go-librespot[1237]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 48 gp=0x1c857a8 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080788, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1dadafc sp=0x1dadae8 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080778, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1dadb14 sp=0x1dadafc pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080778, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1dadb28 sp=0x1dadb14 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1e3cb58, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1dadb3c sp=0x1dadb28 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1e3cb40, {0x1fda000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1dadb84 sp=0x1dadb3c pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1e3cb40, {0x1fda000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1dadbb0 sp=0x1dadb84 pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1d20508, {0x1fda000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1dadbdc sp=0x1dadbb0 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1d20508, {0x1fda000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1dadbfc sp=0x1dadbdc pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x1fd4010, {0x1fda000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1dadc28 sp=0x1dadbfc pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x206138c, {0x7d4554, 0x1fd4010}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1dadc64 sp=0x1dadc28 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x2061208, {0x7d373c, 0x1d20508}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1dadc8c sp=0x1dadc64 pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x2061208, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1daddf0 sp=0x1dadc8c pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x2061208, {0x1f20000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1dade20 sp=0x1daddf0 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).Read(0x1cda0b8, {0x1f20000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x1dade54 sp=0x1dade20 pc=0x3550f8 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).fill(0x204af90) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1dade78 sp=0x1dade54 pc=0x2bd8e0 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Peek(0x204af90, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x1dade84 sp=0x1dade78 pc=0x2bda64 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).readLoop(0x1cda0b8) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x1dadfe4 sp=0x1dade84 pc=0x355c88 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Transport).dialConn.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x1dadfec sp=0x1dadfe4 pc=0x3545a8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1dadfec sp=0x1dadfec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Transport).dialConn in goroutine 43 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 121 gp=0x1f24368 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x760806e8, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1c57afc sp=0x1c57ae8 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x760806d8, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1c57b14 sp=0x1c57afc pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x760806d8, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1c57b28 sp=0x1c57b14 pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1e3cc48, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c57b3c sp=0x1c57b28 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1e3cc30, {0x211c000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1c57b84 sp=0x1c57b3c pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1e3cc30, {0x211c000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1c57bb0 sp=0x1c57b84 pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x1c94490, {0x211c000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1c57bdc sp=0x1c57bb0 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x1c94490, {0x211c000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1c57bfc sp=0x1c57bdc pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*atLeastReader).Read(0x1d0a2f0, {0x211c000, 0xa000, 0xa000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x1c57c28 sp=0x1c57bfc pc=0x27c1ac Jul 02 22:04:17 volumio go-librespot[1237]: bytes.(*Buffer).ReadFrom(0x1ee0f0c, {0x7d4554, 0x1d0a2f0}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x1c57c64 sp=0x1c57c28 pc=0x139b90 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readFromUntil(0x1ee0d88, {0x7d373c, 0x1c94490}, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x1c57c8c sp=0x1c57c64 pc=0x27c408 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecordOrCCS(0x1ee0d88, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x1c57df0 sp=0x1c57c8c pc=0x279b08 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).readRecord(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:588 Jul 02 22:04:17 volumio go-librespot[1237]: crypto/tls.(*Conn).Read(0x1ee0d88, {0x1edd000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x1c57e20 sp=0x1c57df0 pc=0x27f978 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).Read(0x20b2218, {0x1edd000, 0x1000, 0x1000}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x1c57e54 sp=0x1c57e20 pc=0x3550f8 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).fill(0x1c00ff0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x1c57e78 sp=0x1c57e54 pc=0x2bd8e0 Jul 02 22:04:17 volumio go-librespot[1237]: bufio.(*Reader).Peek(0x1c00ff0, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x1c57e84 sp=0x1c57e78 pc=0x2bda64 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).readLoop(0x20b2218) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x1c57fe4 sp=0x1c57e84 pc=0x355c88 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Transport).dialConn.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x1c57fec sp=0x1c57fe4 pc=0x3545a8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1c57fec sp=0x1c57fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Transport).dialConn in goroutine 131 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 122 gp=0x1f24908 m=nil [select]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ec5eb0 sp=0x1ec5e9c pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.selectgo(0x1ec5fac, 0x1ec5f78, 0x0, 0x0, 0x2, 0x1) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x1ec5f50 sp=0x1ec5eb0 pc=0x70d80 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*persistConn).writeLoop(0x20b2218) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x1ec5fe4 sp=0x1ec5f50 pc=0x357748 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*Transport).dialConn.gowrap3() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x1ec5fec sp=0x1ec5fe4 pc=0x354554 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ec5fec sp=0x1ec5fec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*Transport).dialConn in goroutine 131 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 143 gp=0x1f24d88 m=nil [IO wait]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.gopark(0x736854, 0x76080828, 0x2, 0x2, 0x5) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x1ec86b4 sp=0x1ec86a0 pc=0x5cfc8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpollblock(0x76080818, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x1ec86cc sp=0x1ec86b4 pc=0x547d4 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.runtime_pollWait(0x76080818, 0x72) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x1ec86e0 sp=0x1ec86cc pc=0x8f388 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).wait(0x1e3c978, 0x72, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1ec86f4 sp=0x1ec86e0 pc=0x10a2d8 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*pollDesc).waitRead(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 02 22:04:17 volumio go-librespot[1237]: internal/poll.(*FD).Read(0x1e3c960, {0x2113c2d, 0x1, 0x1}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x1ec873c sp=0x1ec86f4 pc=0x10b4c4 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*netFD).Read(0x1e3c960, {0x2113c2d, 0x1, 0x1}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x1ec8768 sp=0x1ec873c pc=0x17c438 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*conn).Read(0x2039638, {0x2113c2d, 0x1, 0x1}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x1ec8794 sp=0x1ec8768 pc=0x18c618 Jul 02 22:04:17 volumio go-librespot[1237]: net.(*TCPConn).Read(0x2039638, {0x2113c2d, 0x1, 0x1}) Jul 02 22:04:17 volumio go-librespot[1237]: :1 +0x44 fp=0x1ec87b4 sp=0x1ec8794 pc=0x19f86c Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*connReader).backgroundRead(0x2113c20) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x1ec87e4 sp=0x1ec87b4 pc=0x335828 Jul 02 22:04:17 volumio go-librespot[1237]: net/http.(*connReader).startBackgroundRead.gowrap2() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x1ec87ec sp=0x1ec87e4 pc=0x335760 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit({}) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x1ec87ec sp=0x1ec87ec pc=0x93c4c Jul 02 22:04:17 volumio go-librespot[1237]: created by net/http.(*connReader).startBackgroundRead in goroutine 142 Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/net/http/server.go:677 +0x160 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0xe Jul 02 22:04:17 volumio go-librespot[1237]: error 0x80000007 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r1 0xfe Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x4 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x83 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0xfe Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x1bac04c Jul 02 22:04:17 volumio go-librespot[1237]: r7 0x1d05040 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1babe90 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0x1b69008 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1f245a8 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x8 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x76f65890 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x72a2ed78 Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x76ecadbc Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x82 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0x20000030 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x82 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c94 m=0 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0xbcb778 m=0 mp=0xbcc020 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0xbcc0e8, 0x80, 0x0, 0x0, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0x7ef7eadc sp=0x7ef7eadc pc=0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0xbcc0e8, 0x0, 0xffffffffffffffff) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0x7ef7eb04 sp=0x7ef7eadc pc=0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notesleep(0xbcc0e8) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0x7ef7eb24 sp=0x7ef7eb04 pc=0x25450 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mPark(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1761 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.stopm() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0x7ef7eb38 sp=0x7ef7eb24 pc=0x61c34 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.findRunnable() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0x7ef7ebe4 sp=0x7ef7eb38 pc=0x638d4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.schedule() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0x7ef7ec00 sp=0x7ef7ebe4 pc=0x64e24 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.goexit0(0x2176368) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:4181 +0x20 fp=0x7ef7ec08 sp=0x7ef7ec00 pc=0x65ce4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mcall(0x7d1dcc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0x7ef7ec10 sp=0x7ef7ec08 pc=0x91f80 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0xbcc0e8 Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0xbcdd20 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0xbcb778 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x6 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x149ad961 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x7ef7eadc Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0xa0000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c98 m=2 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1c026c8 m=2 mp=0x1c48588 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0xbcddac, 0x80, 0x0, 0x768fbd48, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:417 +0x20 fp=0x768fbd28 sp=0x768fbd28 pc=0x94c98 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0xbcddac, 0x0, 0x4dac44f55) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:75 +0x178 fp=0x768fbd50 sp=0x768fbd28 pc=0x558a4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notetsleep_internal(0xbcddac, 0x4dac44f55) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:212 +0x124 fp=0x768fbd78 sp=0x768fbd50 pc=0x255d4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notetsleep(0xbcddac, 0x4dac44f55) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:235 +0x48 fp=0x768fbd8c sp=0x768fbd78 pc=0x25774 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.sysmon() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:6001 +0x2e0 fp=0x768fbde8 sp=0x768fbd8c pc=0x6a720 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0x768fbdf8 sp=0x768fbde8 pc=0x60064 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart0() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0x768fbe0c sp=0x768fbdf8 pc=0x5ffd8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0x768fbe10 sp=0x768fbe0c pc=0x91f20 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0xfffffffc Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x768fbd48 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1c026c8 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x3b9aca00 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x768fbd28 Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x558a4 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c98 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0x60000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c94 m=3 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1c02908 m=3 mp=0x1c48b08 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0x1c48bd0, 0x80, 0x0, 0x0, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0x75efecac sp=0x75efecac pc=0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0x1c48bd0, 0x0, 0xffffffffffffffff) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0x75efecd4 sp=0x75efecac pc=0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notesleep(0x1c48bd0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0x75efecf4 sp=0x75efecd4 pc=0x25450 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mPark(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1761 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.stopm() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0x75efed08 sp=0x75efecf4 pc=0x61c34 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.findRunnable() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0x75efedb4 sp=0x75efed08 pc=0x638d4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.schedule() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0x75efedd0 sp=0x75efedb4 pc=0x64e24 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.park_m(0x1c84c68) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0x75efedf0 sp=0x75efedd0 pc=0x6569c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mcall(0x76ff0f40) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0x75efedf8 sp=0x75efedf0 pc=0x91f80 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0x1c48bd0 Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0xbcdd20 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1c02908 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x6 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x9222eb8a Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x75efecac Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0xa0000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c94 m=4 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1c02b48 m=4 mp=0x1c49088 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0x1c49150, 0x80, 0x0, 0x0, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0x754fecac sp=0x754fecac pc=0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0x1c49150, 0x0, 0xffffffffffffffff) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0x754fecd4 sp=0x754fecac pc=0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notesleep(0x1c49150) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0x754fecf4 sp=0x754fecd4 pc=0x25450 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mPark(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1761 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.stopm() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0x754fed08 sp=0x754fecf4 pc=0x61c34 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.findRunnable() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0x754fedb4 sp=0x754fed08 pc=0x638d4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.schedule() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0x754fedd0 sp=0x754fedb4 pc=0x64e24 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.park_m(0x1c84d88) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0x754fedf0 sp=0x754fedd0 pc=0x6569c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mcall(0x76ff0f40) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0x754fedf8 sp=0x754fedf0 pc=0x91f80 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0x1c49150 Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0xbcdd20 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1c02b48 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x6 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x6516d1f6 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x754fecac Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0xa0000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x1b52c m=5 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1c84128 m=5 mp=0x1c80008 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime/internal/syscall.Syscall6(0x15a, 0x4, 0x74cfd4e8, 0x80, 0x5173, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/internal/syscall/asm_linux_arm.s:17 +0x20 fp=0x74cfd46c sp=0x74cfd46c pc=0x1b52c Jul 02 22:04:17 volumio go-librespot[1237]: runtime/internal/syscall.EpollWait(0x4, {0x74cfd4e8, 0x80, 0x80}, 0x80, 0x5173) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/internal/syscall/syscall_linux.go:55 +0x60 fp=0x74cfd49c sp=0x74cfd46c pc=0x1b484 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.netpoll(0x4dada223c) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/netpoll_epoll.go:118 +0x17c fp=0x74cfdd08 sp=0x74cfd49c pc=0x553c8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.findRunnable() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3470 +0xbbc fp=0x74cfddb4 sp=0x74cfdd08 pc=0x6368c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.schedule() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0x74cfddd0 sp=0x74cfddb4 pc=0x64e24 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.park_m(0x1f24d88) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0x74cfddf0 sp=0x74cfddd0 pc=0x6569c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mcall(0x75eff900) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0x74cfddf8 sp=0x74cfddf0 pc=0x91f80 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0xfffffffc Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x74cfd4e8 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x5173 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0x15a Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1c84128 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0xb87f64 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x920ead80 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x74cfd46c Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x1b484 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x1b52c Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0x20000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c94 m=6 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1c846c8 m=6 mp=0x1c80588 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0xbf67dc, 0x80, 0x0, 0x0, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0x740fed94 sp=0x740fed94 pc=0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0xbf67dc, 0x0, 0xffffffffffffffff) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0x740fedbc sp=0x740fed94 pc=0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notesleep(0xbf67dc) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0x740feddc sp=0x740fedbc pc=0x25450 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.templateThread() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:2760 +0x90 fp=0x740fede8 sp=0x740feddc pc=0x61aec Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart1() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1729 +0x7c fp=0x740fedf8 sp=0x740fede8 pc=0x60064 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart0() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1686 +0x7c fp=0x740fee0c sp=0x740fedf8 pc=0x5ffd8 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mstart() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:210 +0x8 fp=0x740fee10 sp=0x740fee0c pc=0x91f20 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0xbf67dc Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x7ef7ea3a Jul 02 22:04:17 volumio go-librespot[1237]: r9 0x740ff440 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1c846c8 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x6 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0x73700470 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x740fed94 Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0xa0000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: ----- Jul 02 22:04:17 volumio go-librespot[1237]: SIGQUIT: quit Jul 02 22:04:17 volumio go-librespot[1237]: PC=0x94c94 m=7 sigcode=0 Jul 02 22:04:17 volumio go-librespot[1237]: goroutine 0 gp=0x1d237a8 m=7 mp=0x1d35088 [idle]: Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futex(0x1d35150, 0x80, 0x0, 0x0, 0x0, 0x0) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/sys_linux_arm.s:416 +0x1c fp=0x736fecac sp=0x736fecac pc=0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.futexsleep(0x1d35150, 0x0, 0xffffffffffffffff) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/os_linux.go:69 +0x5c fp=0x736fecd4 sp=0x736fecac pc=0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.notesleep(0x1d35150) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/lock_futex.go:170 +0xb8 fp=0x736fecf4 sp=0x736fecd4 pc=0x25450 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mPark(...) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:1761 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.stopm() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:2782 +0x90 fp=0x736fed08 sp=0x736fecf4 pc=0x61c34 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.findRunnable() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3512 +0xe04 fp=0x736fedb4 sp=0x736fed08 pc=0x638d4 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.schedule() Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:3868 +0xac fp=0x736fedd0 sp=0x736fedb4 pc=0x64e24 Jul 02 22:04:17 volumio go-librespot[1237]: runtime.park_m(0x1c84c68) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/proc.go:4036 +0x1b0 fp=0x736fedf0 sp=0x736fedd0 pc=0x6569c Jul 02 22:04:17 volumio go-librespot[1237]: runtime.mcall(0x75eff900) Jul 02 22:04:17 volumio go-librespot[1237]: /usr/local/go/src/runtime/asm_arm.s:265 +0x48 fp=0x736fedf8 sp=0x736fedf0 pc=0x91f80 Jul 02 22:04:17 volumio go-librespot[1237]: trap 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: error 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: oldmask 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r0 0x1d35150 Jul 02 22:04:17 volumio go-librespot[1237]: r1 0x80 Jul 02 22:04:17 volumio go-librespot[1237]: r2 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r3 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r4 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r5 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r6 0x0 Jul 02 22:04:17 volumio go-librespot[1237]: r7 0xf0 Jul 02 22:04:17 volumio go-librespot[1237]: r8 0x1 Jul 02 22:04:17 volumio go-librespot[1237]: r9 0xbcdd20 Jul 02 22:04:17 volumio go-librespot[1237]: r10 0x1d237a8 Jul 02 22:04:17 volumio go-librespot[1237]: fp 0x6 Jul 02 22:04:17 volumio go-librespot[1237]: ip 0xeba8bf31 Jul 02 22:04:17 volumio go-librespot[1237]: sp 0x736fecac Jul 02 22:04:17 volumio go-librespot[1237]: lr 0x55788 Jul 02 22:04:17 volumio go-librespot[1237]: pc 0x94c94 Jul 02 22:04:17 volumio go-librespot[1237]: cpsr 0xa0000010 Jul 02 22:04:17 volumio go-librespot[1237]: fault 0x0 Jul 02 22:04:19 volumio go-librespot[1237]: Aborted Jul 02 22:04:19 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 02 22:04:19 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 02 22:04:19 volumio volumio[960]: error: Failed to send command to Spotify local API: /player/pause: Error: socket hang up Jul 02 22:04:19 volumio volumio[960]: info: Connection to go-librespot Websocket closed Jul 02 22:04:19 volumio volumio[960]: info: CoreCommandRouter::volumioPause Jul 02 22:04:19 volumio volumio[960]: info: CoreStateMachine::pause Jul 02 22:04:20 volumio volumio[960]: info: CoreCommandRouter::volumioPause Jul 02 22:04:20 volumio volumio[960]: info: CoreStateMachine::pause Jul 02 22:04:22 volumio volumio[960]: info: CoreCommandRouter::volumioPause Jul 02 22:04:22 volumio volumio[960]: info: CoreStateMachine::pause Jul 02 22:04:22 volumio volumio[960]: info: Initializing connection to go-librespot Websocket Jul 02 22:04:22 volumio volumio[960]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 02 22:04:22 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Jul 02 22:04:22 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 02 22:04:22 volumio systemd[1]: Stopped go-librespot Daemon. Jul 02 22:04:22 volumio systemd[1]: Started go-librespot Daemon. Jul 02 22:04:22 volumio go-librespot[1914]: go-librespot daemon starting... Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=info msg="running go-librespot 0.2.0" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=debug msg="app state loaded" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=info msg="zeroconf server listening on port 39863" Jul 02 22:04:22 volumio go-librespot[1914]: time="2025-07-02T22:04:22+02:00" level=debug msg="obtained new client token: AAAq1srfBcyTnOpBoenISakRWYlaR9axE8JI4tCNf7DsKJyaAQhNWhOND2r+DlAqpC4o9oV9nHttf7pV2O0S7oXMCUX8vdYoIF4gQgsHdj23npGsruf3zjxUU9QhDEN5KSjVLbbNVuDPfXAqSAykP9P5OvEPmh2Ot0FACVQVKNZ3jF6u9za3TzCe6YN7VTeHNpEC8jeqSBse+6lQGEETlsqAR0+9Z8t6fL34B45CLF0BrXf25mqLRhrEfw==" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="completed keyexchange" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="completed challenge" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=info msg="authenticated AP as avesanimax" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=info msg="authenticated Login5 as avesanimax" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="initializing zeroconf session, username: avesanimax" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="dealer connection opened" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=trace msg="starting accesspoint recv loop" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=trace msg="starting dealer recv loop" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=trace msg="received accesspoint ping" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="received connection id: NzdlYmJmNzQtY2E5Yi00YzdkLWE5ZTAtNzkxYTA3MmZkNTQyK2RlYWxlcit0Y3A6Ly8wYWIxNTIxYy5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQzA4MEVCODRBRDhFQThENzgzQzlFMDI3NEQyMTY1NjYyNjY1QzYzNDk1N0EzMjA0OTYxMjdBQTNGQTk1NUQwRA==" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=trace msg="received accesspoint pong ack" Jul 02 22:04:23 volumio go-librespot[1914]: time="2025-07-02T22:04:23+02:00" level=debug msg="put connect state because NEW_DEVICE" Jul 02 22:04:25 volumio volumio[960]: info: Initializing connection to go-librespot Websocket Jul 02 22:04:25 volumio go-librespot[1914]: time="2025-07-02T22:04:25+02:00" level=debug msg="new websocket client" Jul 02 22:04:25 volumio volumio[960]: info: Connection to go-librespot Websocket established Jul 02 22:04:28 volumio volumio[960]: info: Getting Spotify volume Jul 02 22:04:28 volumio volumio[960]: info: Spotify volume: 100 Jul 02 22:04:28 volumio volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Jul 02 22:04:28 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:28 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:28 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 35 Jul 02 22:04:28 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME 100 Jul 02 22:04:28 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 35 Jul 02 22:04:28 volumio volumio[960]: SPOTIFY: DELTA VOLUME ENOUGH: true Jul 02 22:04:28 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 35 Jul 02 22:04:29 volumio volumio[960]: SPOTIFY: SETTING SPOTIFY VOLUME 35 Jul 02 22:04:29 volumio volumio[960]: info: Sending Spotify command with payload to local API: /player/volume Jul 02 22:04:30 volumio go-librespot[1914]: time="2025-07-02T22:04:30+02:00" level=debug msg="update volume to 22937/65535" Jul 02 22:04:31 volumio go-librespot[1914]: time="2025-07-02T22:04:31+02:00" level=debug msg="put connect state because VOLUME_CHANGED" Jul 02 22:04:31 volumio go-librespot[1914]: time="2025-07-02T22:04:31+02:00" level=trace msg="emitting websocket event: volume" Jul 02 22:04:31 volumio volumio[960]: SPOTIFY: received: {"type":"volume","data":{"value":35,"max":100}} Jul 02 22:04:31 volumio volumio[960]: SPOTIFY: RECEIVED SPOTIFY VOLUME 35 Jul 02 22:04:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 22:04:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 02 22:04:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:04:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:04:35 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:04:35 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:35 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:04:37 volumio sudo[1940]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 02 22:04:37 volumio sudo[1940]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:37 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Jul 02 22:04:37 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:37 volumio sudo[1940]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:37 volumio sudo[1945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Jul 02 22:04:37 volumio sudo[1945]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:37 volumio volumio[960]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 02 22:04:37 volumio sudo[1948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Jul 02 22:04:37 volumio sudo[1948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:37 volumio systemd[1]: Created slice system-getty.slice. Jul 02 22:04:37 volumio systemd[1]: Started Getty on tty1. Jul 02 22:04:37 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:37 volumio volumio[960]: info: touch_display: systemctl start getty@tty1.service succeeded. Jul 02 22:04:37 volumio systemd[1]: Reloading. Jul 02 22:04:37 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 02 22:04:37 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Jul 02 22:04:38 volumio sudo[1945]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:38 volumio volumio[960]: info: touch_display: systemctl enable getty@tty1.service succeeded. Jul 02 22:04:38 volumio systemd[1]: Stopping Volumio Kiosk... Jul 02 22:04:38 volumio startx[1235]: xinit: connection to X server lost Jul 02 22:04:38 volumio startx[1235]: [1B blob data] Jul 02 22:04:38 volumio startx[1235]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Jul 02 22:04:39 volumio startx[1235]: xinit: unexpected signal 15 Jul 02 22:04:39 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jul 02 22:04:39 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jul 02 22:04:39 volumio systemd[1]: Stopped Volumio Kiosk. Jul 02 22:04:39 volumio sudo[1948]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:39 volumio volumio[960]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Jul 02 22:04:39 volumio volumio[960]: info: Disabling plugin touch_display Jul 02 22:04:39 volumio volumio[960]: info: Done. Jul 02 22:04:41 volumio volumio[960]: info: Enabling plugin touch_display Jul 02 22:04:41 volumio volumio[960]: info: Loading plugin "touch_display"... Jul 02 22:04:41 volumio volumio[960]: info: Applying required configuration parameters for plugin touch_display Jul 02 22:04:41 volumio volumio[960]: info: PLUGIN START: touch_display Jul 02 22:04:41 volumio volumio[960]: info: Loading i18n strings for locale it Jul 02 22:04:41 volumio volumio[960]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Jul 02 22:04:41 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 22:04:42 volumio sudo[1983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 02 22:04:42 volumio sudo[1983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:42 volumio sudo[1985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 02 22:04:42 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:42 volumio volumio[960]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Jul 02 22:04:42 volumio systemd[1]: Stopping Getty on tty1... Jul 02 22:04:42 volumio systemd[1]: getty@tty1.service: Main process exited, code=killed, status=15/TERM Jul 02 22:04:42 volumio systemd[1]: getty@tty1.service: Succeeded. Jul 02 22:04:42 volumio systemd[1]: Stopped Getty on tty1. Jul 02 22:04:42 volumio sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 02 22:04:42 volumio sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:42 volumio volumio[960]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 02 22:04:42 volumio systemd[1]: Reloading. Jul 02 22:04:42 volumio sudo[1983]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:42 volumio sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 02 22:04:42 volumio sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:42 volumio sudo[1991]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:42 volumio volumio[960]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 02 22:04:42 volumio volumio[960]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 02 22:04:42 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Jul 02 22:04:42 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:42 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:42 volumio volumio[960]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Jul 02 22:04:42 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 02 22:04:42 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:42 volumio volumio[960]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 02 22:04:42 volumio systemd[1]: Reloading. Jul 02 22:04:43 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 02 22:04:43 volumio sudo[1988]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:43 volumio volumio[960]: info: touch_display: systemctl daemon-reload succeeded. Jul 02 22:04:43 volumio sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jul 02 22:04:43 volumio sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:04:43 volumio systemd[1]: Started Volumio Kiosk. Jul 02 22:04:43 volumio sudo[2026]: pam_unix(sudo:session): session closed for user root Jul 02 22:04:43 volumio volumio[960]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jul 02 22:04:43 volumio volumio[960]: info: touch_display: Volumio Kiosk started. Jul 02 22:04:43 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:43 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:43 volumio volumio[960]: info: Done. Jul 02 22:04:43 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:43 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:43 volumio volumio[960]: info: touch_display: X display number found: Jul 02 22:04:43 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:04:43 volumio startx[2028]: X.Org X Server 1.20.4 Jul 02 22:04:43 volumio startx[2028]: X Protocol Version 11, Revision 0 Jul 02 22:04:43 volumio startx[2028]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 02 22:04:43 volumio startx[2028]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jul 02 22:04:43 volumio startx[2028]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=512 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=35e4e60f-5b83-4298-b8fb-dded911033bb imgfile=/volumio_current.sqsh bootpart=UUID=9744-01A5 datapart=UUID=671f4730-2491-45d9-ba08-57ee2c6a1695 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 02 22:04:43 volumio startx[2028]: Build Date: 04 April 2023 07:50:56AM Jul 02 22:04:43 volumio startx[2028]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 02 22:04:43 volumio startx[2028]: Current version of pixman: 0.36.0 Jul 02 22:04:43 volumio startx[2028]: Before reporting problems, check http://wiki.x.org Jul 02 22:04:43 volumio startx[2028]: to make sure that you have the latest version. Jul 02 22:04:43 volumio startx[2028]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 02 22:04:43 volumio startx[2028]: (++) from command line, (!!) notice, (II) informational, Jul 02 22:04:43 volumio startx[2028]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 02 22:04:43 volumio startx[2028]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jul 2 22:04:43 2025 Jul 02 22:04:43 volumio startx[2028]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 02 22:04:43 volumio startx[2028]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 02 22:04:43 volumio volumio[960]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 02 22:04:43 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:04:44 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:44 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:44 volumio volumio[960]: info: touch_display: Setting screensaver timeout to 120 seconds. Jul 02 22:04:51 volumio volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Jul 02 22:04:53 volumio go-librespot[1914]: time="2025-07-02T22:04:53+02:00" level=trace msg="sent dealer ping" Jul 02 22:04:53 volumio volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Jul 02 22:04:53 volumio go-librespot[1914]: time="2025-07-02T22:04:53+02:00" level=trace msg="received dealer pong" Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:54 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 02 22:04:54 volumio volumio[960]: info: Listing playlists Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jul 02 22:04:54 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 22:04:55 volumio volumio[960]: info: Received Get System Info Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:04:55 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:04:55 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:04:55 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Jul 02 22:04:57 volumio volumio[960]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 02 22:05:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 22:05:01 volumio volumio[960]: info: Received Get System Info Jul 02 22:05:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:05:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:05:01 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:05:01 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:05:01 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:05:01 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:05:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jul 02 22:05:02 volumio volumio[960]: info: Received Get System Info Jul 02 22:05:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:05:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:05:02 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:05:02 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:05:02 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:05:02 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:05:03 volumio wpa_supplicant[861]: RRM: Ignoring radio measurement request: Not RRM network Jul 02 22:05:05 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:05:05 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:05:05 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:05:05 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:05:05 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:05:05 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:05:05 volumio wpa_supplicant[861]: RRM: Ignoring radio measurement request: Not RRM network Jul 02 22:05:05 volumio volumio[960]: info: CALLMETHOD: user_interface touch_display saveScreensaverConf [object Object] Jul 02 22:05:05 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScreensaverConf Jul 02 22:05:07 volumio wpa_supplicant[861]: RRM: Ignoring radio measurement request: Not RRM network Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jul 02 22:05:11 volumio volumio[960]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jul 02 22:05:11 volumio volumio[960]: info: Received Get System Version Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 02 22:05:11 volumio volumio[960]: info: Received Get System Info Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 02 22:05:11 volumio volumio[960]: info: Discovery: Getting this device information Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Jul 02 22:05:11 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Jul 02 22:05:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 02 22:05:22 volumio volumio[960]: info: CALLMETHOD: user_interface touch_display saveScaleConf [object Object] Jul 02 22:05:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScaleConf Jul 02 22:05:23 volumio go-librespot[1914]: time="2025-07-02T22:05:23+02:00" level=trace msg="sent dealer ping" Jul 02 22:05:23 volumio go-librespot[1914]: time="2025-07-02T22:05:23+02:00" level=trace msg="received dealer pong" Jul 02 22:05:24 volumio volumio[960]: info: CALLMETHOD: user_interface touch_display saveScaleConf [object Object] Jul 02 22:05:24 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: touch_display , saveScaleConf Jul 02 22:05:24 volumio sudo[2564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/factor=.* /factor=0.8 / /opt/volumiokiosk.sh Jul 02 22:05:24 volumio sudo[2564]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:05:24 volumio sudo[2564]: pam_unix(sudo:session): session closed for user root Jul 02 22:05:24 volumio sudo[2573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Jul 02 22:05:24 volumio sudo[2573]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:05:24 volumio startx[2028]: xinit: connection to X server lost Jul 02 22:05:24 volumio systemd[1]: Stopping Volumio Kiosk... Jul 02 22:05:24 volumio startx[2028]: [1B blob data] Jul 02 22:05:24 volumio startx[2028]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Jul 02 22:05:25 volumio sudo[2584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sed -i -e s/factor=.* /factor=0.8 / /opt/volumiokiosk.sh Jul 02 22:05:25 volumio sudo[2584]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:05:25 volumio sudo[2584]: pam_unix(sudo:session): session closed for user root Jul 02 22:05:25 volumio sudo[2587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-kiosk.service Jul 02 22:05:25 volumio sudo[2587]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 02 22:05:25 volumio startx[2028]: xinit: unexpected signal 15 Jul 02 22:05:25 volumio systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Jul 02 22:05:25 volumio systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Jul 02 22:05:25 volumio systemd[1]: Stopped Volumio Kiosk. Jul 02 22:05:25 volumio systemd[1]: Started Volumio Kiosk. Jul 02 22:05:25 volumio sudo[2587]: pam_unix(sudo:session): session closed for user root Jul 02 22:05:25 volumio sudo[2573]: pam_unix(sudo:session): session closed for user root Jul 02 22:05:25 volumio volumio[960]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jul 02 22:05:25 volumio volumio[960]: info: touch_display: Volumio Kiosk restarted. Jul 02 22:05:25 volumio volumio[960]: info: touch_display: systemctl restart volumio-kiosk.service succeeded. Jul 02 22:05:25 volumio volumio[960]: info: touch_display: Volumio Kiosk restarted. Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: Jul 02 22:05:25 volumio startx[2591]: X.Org X Server 1.20.4 Jul 02 22:05:25 volumio startx[2591]: X Protocol Version 11, Revision 0 Jul 02 22:05:25 volumio startx[2591]: Build Operating System: Linux 5.4.0-109-generic armv8l Raspbian Jul 02 22:05:25 volumio startx[2591]: Current Operating System: Linux volumio 6.6.62-v7+ #1816 SMP Mon Nov 18 15:30:49 GMT 2024 armv7l Jul 02 22:05:25 volumio startx[2591]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=656 bcm2708_fb.fbheight=512 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=35e4e60f-5b83-4298-b8fb-dded911033bb imgfile=/volumio_current.sqsh bootpart=UUID=9744-01A5 datapart=UUID=671f4730-2491-45d9-ba08-57ee2c6a1695 uuidconfig=cmdline.txt pcie_aspm=off pci=pcie_bus_safe rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd-bcm2835.enable_compat_alsa= snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no Jul 02 22:05:25 volumio startx[2591]: Build Date: 04 April 2023 07:50:56AM Jul 02 22:05:25 volumio startx[2591]: xorg-server 2:1.20.4-1+rpt4+deb10u9 (https://www.debian.org/support) Jul 02 22:05:25 volumio startx[2591]: Current version of pixman: 0.36.0 Jul 02 22:05:25 volumio startx[2591]: Before reporting problems, check http://wiki.x.org Jul 02 22:05:25 volumio startx[2591]: to make sure that you have the latest version. Jul 02 22:05:25 volumio startx[2591]: Markers: (--) probed, (**) from config file, (==) default setting, Jul 02 22:05:25 volumio startx[2591]: (++) from command line, (!!) notice, (II) informational, Jul 02 22:05:25 volumio startx[2591]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jul 02 22:05:25 volumio startx[2591]: (==) Log file: "/var/log/Xorg.0.log", Time: Wed Jul 2 22:05:25 2025 Jul 02 22:05:25 volumio startx[2591]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jul 02 22:05:25 volumio startx[2591]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:25 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:26 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:26 volumio volumio[960]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 02 22:05:26 volumio volumio[960]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jul 02 22:05:26 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:26 volumio volumio[960]: info: touch_display: X display number found: 0 Jul 02 22:05:26 volumio volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 22:05:26 volumio volumio[960]: Error: connect EISCONN /tmp/.X11-unix/X0 Jul 02 22:05:26 volumio volumio[960]: at PipeConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Jul 02 22:05:26 volumio volumio[960]: errno: -106, Jul 02 22:05:26 volumio volumio[960]: code: 'EISCONN', Jul 02 22:05:26 volumio volumio[960]: syscall: 'connect', Jul 02 22:05:26 volumio volumio[960]: address: '/tmp/.X11-unix/X0' Jul 02 22:05:26 volumio volumio[960]: } Jul 02 22:05:26 volumio volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 02 22:05:27 volumio sudo[2717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-02 22:04 Jul 02 22:05:27 volumio sudo[2717]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 10 Jun 2025 04:52:53 PM CEST" VOLUMIO_VERSION="3.816" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="a72866a0de4045751d03a035de6290e1"