-- Logs begin at Sun 2025-03-16 22:19:39 +07, end at Mon 2025-03-17 18:13:20 +07. -- Mar 17 18:12:41 tu-mn systemd-timedated[855]: Changed local time to Mon Mar 17 18:12:41 2025 Mar 17 18:12:41 tu-mn sudo[853]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:41 tu-mn volumio-time-update[471]: volumio-time-update-util: System time updated successfully. Mar 17 18:12:41 tu-mn systemd[1]: Starting Daily apt download activities... Mar 17 18:12:41 tu-mn systemd[1]: Started Volumio Time Update Utility. Mar 17 18:12:41 tu-mn nmbd[638]: [2025/03/17 18:12:41.923235, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Mar 17 18:12:41 tu-mn nmbd[638]: register_name_response: Answer name <00> differs from question name <00>. Mar 17 18:12:41 tu-mn nmbd[638]: [2025/03/17 18:12:41.923655, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Mar 17 18:12:41 tu-mn nmbd[638]: register_name_response: Answer name <03> differs from question name <03>. Mar 17 18:12:41 tu-mn nmbd[638]: [2025/03/17 18:12:41.923957, 0] ../source3/nmbd/nmbd_nameregister.c:73(register_name_response) Mar 17 18:12:41 tu-mn nmbd[638]: register_name_response: Answer name <20> differs from question name <20>. Mar 17 18:12:42 tu-mn systemd[1]: apt-daily.service: Succeeded. Mar 17 18:12:42 tu-mn systemd[1]: Started Daily apt download activities. Mar 17 18:12:42 tu-mn systemd[1]: Starting Daily apt upgrade and clean activities... Mar 17 18:12:43 tu-mn systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 17 18:12:43 tu-mn systemd[1]: Started Daily apt upgrade and clean activities. Mar 17 18:12:44 tu-mn sudo[841]: root : unable to resolve host tu-mn Mar 17 18:12:44 tu-mn sudo[841]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:44 tu-mn sudo[841]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 17 18:12:44 tu-mn sudo[841]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:44 tu-mn sudo[841]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:47 tu-mn volumio-remote-updater[461]: [2025-03-17 18:12:47] [info] asio async_connect error: system:111 (Connection refused) Mar 17 18:12:47 tu-mn volumio-remote-updater[461]: [2025-03-17 18:12:47] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Mar 17 18:12:47 tu-mn volumio-remote-updater[461]: [2025-03-17 18:12:47] [error] handle_connect error: Underlying Transport Error Mar 17 18:12:49 tu-mn sudo[843]: volumio : unable to resolve host tu-mn Mar 17 18:12:49 tu-mn sudo[843]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:49 tu-mn sudo[843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Mar 17 18:12:49 tu-mn sudo[843]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:49 tu-mn sudo[843]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:49 tu-mn wireless.js[465]: sudo: unable to resolve host tu-mn: System error Mar 17 18:12:49 tu-mn wireless.js[465]: Connected to: ----Gia Huy Mar 17 18:12:49 tu-mn wireless.js[465]: ---- Mar 17 18:12:49 tu-mn wireless.js[465]: ... joined AP, wlan0 IPv4 is 192.168.1.20, ipV6 is undefined Mar 17 18:12:49 tu-mn wireless.js[465]: It's done! AP Mar 17 18:12:49 tu-mn sudo[958]: root : unable to resolve host tu-mn Mar 17 18:12:49 tu-mn sudo[958]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:49 tu-mn sudo[958]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 17 18:12:49 tu-mn sudo[958]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:49 tu-mn sudo[958]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:49 tu-mn wireless.js[465]: ... joined AP, wlan0 IPv4 is 192.168.1.20, ipV6 is undefined Mar 17 18:12:49 tu-mn wireless.js[465]: It's done! AP Mar 17 18:12:49 tu-mn systemd[1]: Started Wireless Services. Mar 17 18:12:49 tu-mn kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Mar 17 18:12:49 tu-mn systemd[1]: Started Volumio Backend Module. Mar 17 18:12:49 tu-mn systemd[1]: Started Volumio Cpu Tweaker. Mar 17 18:12:49 tu-mn systemd[1]: Reached target Multi-User System. Mar 17 18:12:49 tu-mn systemd[1]: Reached target Graphical Interface. Mar 17 18:12:49 tu-mn systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: Setting RT Priority for mpd Mar 17 18:12:49 tu-mn systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 17 18:12:49 tu-mn systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 17 18:12:49 tu-mn systemd[1]: Startup finished in 13.738s (kernel) + 30.836s (userspace) = 44.574s. Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: Setting MPD Affinity Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: pid 671's current affinity mask: f Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: pid 671's new affinity mask: 3 Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Mar 17 18:12:49 tu-mn volumio-cpu-tweak[965]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Mar 17 18:12:49 tu-mn systemd[1]: volumio_cpu_tweak.service: Succeeded. Mar 17 18:12:52 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:12:52 tu-mn volumio[964]: info: ----- Volumio3 ---- Mar 17 18:12:52 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:12:52 tu-mn volumio[964]: info: ----- System startup ---- Mar 17 18:12:52 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:12:52 tu-mn systemd[1]: systemd-fsckd.service: Succeeded. Mar 17 18:12:53 tu-mn volumio[964]: info: MYVOLUMIO Environment detected Mar 17 18:12:54 tu-mn volumio[964]: info: Plugin folders cleanup Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning into folder /volumio/app/plugins/ Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category audio_interface Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category miscellanea Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category music_service Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category plugins.json Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category system_controller Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category user_interface Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning into folder /data/plugins/ Mar 17 18:12:54 tu-mn volumio[964]: info: Scanning category music_service Mar 17 18:12:54 tu-mn volumio[964]: info: Plugin folders cleanup completed Mar 17 18:12:54 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:12:54 tu-mn volumio[964]: info: ----- Core plugins startup ---- Mar 17 18:12:54 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:12:54 tu-mn volumio[964]: info: Loading plugins from folder /volumio/app/plugins/ Mar 17 18:12:54 tu-mn volumio[964]: info: Adding plugin upnp to MyMusic Plugins Mar 17 18:12:54 tu-mn volumio[964]: info: Adding plugin airplay_emulation to MyMusic Plugins Mar 17 18:12:54 tu-mn volumio[964]: info: Adding plugin upnp_browser to MyMusic Plugins Mar 17 18:12:54 tu-mn volumio[964]: info: Loading plugins from folder /data/plugins/ Mar 17 18:12:54 tu-mn volumio[964]: info: Loading plugin "system"... Mar 17 18:12:54 tu-mn volumio[964]: info: Loading plugin "appearance"... Mar 17 18:12:55 tu-mn volumio[964]: info: Loading plugin "network"... Mar 17 18:12:55 tu-mn volumio[964]: info: Refreshing Cached IP Addresses Mar 17 18:12:55 tu-mn volumio[964]: info: Loading plugin "services"... Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "alsa_controller"... Mar 17 18:12:56 tu-mn sudo[1004]: volumio : unable to resolve host tu-mn Mar 17 18:12:56 tu-mn sudo[1004]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:56 tu-mn sudo[1004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 17 18:12:56 tu-mn sudo[1004]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:56 tu-mn sudo[1001]: volumio : unable to resolve host tu-mn Mar 17 18:12:56 tu-mn sudo[1001]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:56 tu-mn sudo[999]: volumio : unable to resolve host tu-mn Mar 17 18:12:56 tu-mn sudo[999]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:12:56 tu-mn sudo[1001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 17 18:12:56 tu-mn sudo[1001]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:56 tu-mn sudo[999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 17 18:12:56 tu-mn sudo[999]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:12:56 tu-mn sudo[1001]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:56 tu-mn sudo[999]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:56 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "wizard"... Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "networkfs"... Mar 17 18:12:56 tu-mn volumio[964]: info: Starting Udev Watcher for removable devices Mar 17 18:12:56 tu-mn volumio[964]: info: Ignoring mount for partition: boot Mar 17 18:12:56 tu-mn volumio[964]: info: Ignoring mount for partition: volumio Mar 17 18:12:56 tu-mn volumio[964]: info: Ignoring mount for partition: volumio_data Mar 17 18:12:56 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "volumio_command_line_client"... Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "upnp"... Mar 17 18:12:56 tu-mn volumio[964]: info: [1742209976372] Starting Upmpd Daemon Mar 17 18:12:56 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "my_music"... Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "mpd"... Mar 17 18:12:56 tu-mn volumio[964]: info: Loading plugin "upnp_browser"... Mar 17 18:12:57 tu-mn sudo[1004]: pam_unix(sudo:session): session closed for user root Mar 17 18:12:57 tu-mn volumio-remote-updater[461]: [2025-03-17 18:12:57] [connect] Successful connection Mar 17 18:12:57 tu-mn systemd[1]: systemd-hostnamed.service: Succeeded. Mar 17 18:12:57 tu-mn volumio[964]: info: Loading plugin "alarm-clock"... Mar 17 18:12:57 tu-mn volumio[964]: info: Loading plugin "airplay_emulation"... Mar 17 18:12:57 tu-mn volumio[964]: info: Starting Shairport Sync Mar 17 18:12:57 tu-mn volumio[964]: info: Loading plugin "last_100"... Mar 17 18:12:57 tu-mn volumio[964]: info: Loading plugin "webradio"... Mar 17 18:12:58 tu-mn volumio[964]: info: Loading plugin "i2s_dacs"... Mar 17 18:12:58 tu-mn volumio[964]: info: Loading plugin "volumiodiscovery"... Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** For more information see Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:12:58 tu-mn volumio[964]: *** WARNING *** For more information see Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** For more information see Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** Please fix your application to use the native API of Avahi! Mar 17 18:12:58 tu-mn node[964]: *** WARNING *** For more information see Mar 17 18:12:58 tu-mn volumio[964]: info: Applying required configuration parameters for plugin volumiodiscovery Mar 17 18:12:58 tu-mn volumio[964]: info: Discovery: Started advertising with name: Tuệ Mẫn Mar 17 18:12:58 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 17 18:12:58 tu-mn volumio[964]: info: Loading plugin "spop"... Mar 17 18:13:00 tu-mn volumio[964]: info: Loading plugin "ytcr"... Mar 17 18:13:06 tu-mn volumio[964]: info: Loading plugin "outputs"... Mar 17 18:13:06 tu-mn volumio[964]: info: Loading plugin "albumart"... Mar 17 18:13:06 tu-mn volumio[964]: info: Plugin example_plugin is not enabled Mar 17 18:13:06 tu-mn volumio[964]: info: Loading plugin "inputs"... Mar 17 18:13:06 tu-mn volumio[964]: info: Loading plugin "updater_comm"... Mar 17 18:13:07 tu-mn volumio[964]: info: Plugin mpdemulation is not enabled Mar 17 18:13:07 tu-mn volumio[964]: info: Loading plugin "rest_api"... Mar 17 18:13:07 tu-mn volumio[964]: info: Loading plugin "websocket"... Mar 17 18:13:07 tu-mn volumio[964]: info: Starting Socket.io Server version 2.3.0 Mar 17 18:13:07 tu-mn volumio[964]: info: Loading i18n strings for locale vi Mar 17 18:13:07 tu-mn volumio[964]: Updating browse sources language Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:13:07 tu-mn volumio[964]: Forking 3 albumart workers Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::initPlayerControls Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 18:13:07 tu-mn volumio[964]: Express server listening on port 3000 Mar 17 18:13:07 tu-mn volumio[964]: [Metrics] WebUI: 16s 399.81ms Mar 17 18:13:07 tu-mn volumio[964]: info: CoreStateMachine::resetVolumioState Mar 17 18:13:07 tu-mn volumio[964]: info: CoreStateMachine::getcurrentVolume Mar 17 18:13:07 tu-mn volumio[964]: info: CoreCommandRouter::volumioRetrievevolume Mar 17 18:13:08 tu-mn volumio[964]: info: Cannot read play queue from file Mar 17 18:13:08 tu-mn volumio[964]: info: Volumio Network Manager: Network status updated: 2 Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::pushState Mar 17 18:13:08 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioPushState Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::updateTrackBlock Mar 17 18:13:08 tu-mn volumio[964]: info: CorePlayQueue::getTrackBlock Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioRetrievevolume Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:08 tu-mn volumio[964]: info: Setting Device type: Raspberry PI Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::setRepeat null single undefined Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::pushState Mar 17 18:13:08 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioPushState Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::setRandom null Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::pushState Mar 17 18:13:08 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioPushState Mar 17 18:13:08 tu-mn volumio[964]: info: Completed loading Core Plugins Mar 17 18:13:08 tu-mn volumio[964]: info: Preparing to generate the ALSA configuration file Mar 17 18:13:08 tu-mn sudo[1085]: volumio : unable to resolve host tu-mn Mar 17 18:13:08 tu-mn sudo[1085]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:08 tu-mn volumio[964]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Mar 17 18:13:08 tu-mn volumio[964]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Mar 17 18:13:08 tu-mn volumio[964]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Mar 17 18:13:08 tu-mn volumio[964]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 18:13:08 tu-mn volumio[964]: info: Reading ALSA contributions from plugins. Mar 17 18:13:08 tu-mn volumio[964]: info: CoreStateMachine::pushState Mar 17 18:13:08 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioPushState Mar 17 18:13:08 tu-mn sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 17 18:13:08 tu-mn sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:08 tu-mn sudo[1085]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:08 tu-mn volumio[964]: info: Asound.conf file unchanged, so no further update is needed Mar 17 18:13:08 tu-mn volumio[964]: info: Output device has changed, restarting MPD Mar 17 18:13:08 tu-mn volumio[964]: info: Output device has changed, restarting Shairport Sync Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:08 tu-mn volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 18:13:08 tu-mn volumio[964]: info: ___________ START PLUGINS ___________ Mar 17 18:13:08 tu-mn sudo[1099]: volumio : unable to resolve host tu-mn Mar 17 18:13:08 tu-mn sudo[1099]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:08 tu-mn sudo[1099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 17 18:13:08 tu-mn sudo[1099]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:08 tu-mn volumio[964]: info: ControllerMpd::onStart: Initializing MPD Mar 17 18:13:08 tu-mn volumio[964]: info: Creating MPD Configuration file Mar 17 18:13:08 tu-mn sudo[1101]: volumio : unable to resolve host tu-mn Mar 17 18:13:08 tu-mn sudo[1101]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:08 tu-mn sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 18:13:08 tu-mn sudo[1103]: volumio : unable to resolve host tu-mn Mar 17 18:13:08 tu-mn sudo[1103]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 18:13:08 tu-mn systemd[1]: Started UPnP Renderer front-end to MPD. Mar 17 18:13:08 tu-mn sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 17 18:13:08 tu-mn sudo[1101]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:08 tu-mn sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 18:13:08 tu-mn sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:08 tu-mn volumio[964]: info: [1742209988839] CoreMusicLibrary::Adding element Máy chủ Media Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:13:08 tu-mn sudo[1099]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:08 tu-mn systemd[1]: Stopping Music Player Daemon... Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:08 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:08 tu-mn sudo[1110]: volumio : unable to resolve host tu-mn Mar 17 18:13:08 tu-mn sudo[1110]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:08 tu-mn sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 18:13:08 tu-mn sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:08 tu-mn sudo[1110]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:09 tu-mn sudo[1114]: volumio : unable to resolve host tu-mn Mar 17 18:13:09 tu-mn sudo[1114]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:09 tu-mn sudo[1114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 18:13:09 tu-mn volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 18:13:09 tu-mn sudo[1114]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:09 tu-mn volumio[964]: Starting albumart workers Mar 17 18:13:09 tu-mn volumio[964]: Starting albumart workers Mar 17 18:13:09 tu-mn volumio[964]: Starting albumart workers Mar 17 18:13:09 tu-mn volumio[964]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 17 18:13:09 tu-mn volumio[964]: info: [1742209989127] CoreMusicLibrary::Adding element Last_100 Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 17 18:13:09 tu-mn volumio[964]: info: [1742209989132] CoreMusicLibrary::Adding element Webradio Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 18:13:09 tu-mn volumio[964]: info: Initializing BBC Radios Mar 17 18:13:09 tu-mn volumio[964]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:09 tu-mn volumio[964]: info: Creating Spotify config file Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:09 tu-mn volumio[964]: info: Volumio Calling Home Mar 17 18:13:09 tu-mn systemd[1]: mpd.service: Succeeded. Mar 17 18:13:09 tu-mn systemd[1]: Stopped Music Player Daemon. Mar 17 18:13:09 tu-mn systemd[1]: Starting Music Player Daemon... Mar 17 18:13:09 tu-mn volumio[964]: info: Discovery: adding a14b1f7c-6372-4554-a133-047792398a93 Mar 17 18:13:09 tu-mn volumio[964]: info: Discovery: Found device Tuệ Mẫn Mar 17 18:13:09 tu-mn volumio[964]: info: CoreCommandRouter::volumioGetState Mar 17 18:13:09 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:10 tu-mn sudo[1135]: root : unable to resolve host tu-mn Mar 17 18:13:10 tu-mn sudo[1135]: root : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:10 tu-mn sudo[1135]: sudo: unable to resolve host tu-mn: System error Mar 17 18:13:10 tu-mn sudo[1135]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 18:13:10 tu-mn sudo[1135]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:10 tu-mn volumio[964]: info: MPD Permissions set Mar 17 18:13:10 tu-mn volumio[964]: info: MPD Permissions set Mar 17 18:13:10 tu-mn volumio[964]: info: Upmpdcli Daemon Started Mar 17 18:13:10 tu-mn sudo[1135]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:10 tu-mn volumio[964]: info: Spotify config file written Mar 17 18:13:10 tu-mn volumio[964]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 17 18:13:10 tu-mn volumio[964]: info: No need to fix Spotify hosts Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn sudo[1140]: volumio : unable to resolve host tu-mn Mar 17 18:13:10 tu-mn sudo[1140]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 18:13:10 tu-mn sudo[1140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 17 18:13:10 tu-mn sudo[1140]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:10 tu-mn systemd[1]: Started go-librespot Daemon. Mar 17 18:13:10 tu-mn go-librespot[1174]: go-librespot daemon starting... Mar 17 18:13:10 tu-mn sudo[1140]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:10 tu-mn volumio[964]: info: Volumio called home Mar 17 18:13:10 tu-mn volumio[964]: info: Starting Shairport Sync Mar 17 18:13:10 tu-mn volumio[964]: info: Starting Shairport Sync Mar 17 18:13:10 tu-mn volumio[964]: info: Starting Shairport Sync Mar 17 18:13:10 tu-mn volumio[964]: info: CoreCommandRouter::volumioGetState Mar 17 18:13:10 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:11 tu-mn sudo[1177]: volumio : unable to resolve host tu-mn Mar 17 18:13:11 tu-mn sudo[1181]: volumio : unable to resolve host tu-mn Mar 17 18:13:11 tu-mn sudo[1181]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:11 tu-mn sudo[1179]: volumio : unable to resolve host tu-mn Mar 17 18:13:11 tu-mn sudo[1179]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:11 tu-mn sudo[1177]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:11 tu-mn sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 18:13:11 tu-mn sudo[1179]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 18:13:11 tu-mn sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:11 tu-mn sudo[1179]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:11 tu-mn sudo[1177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 18:13:11 tu-mn sudo[1177]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:11 tu-mn systemd[1]: systemd-timedated.service: Succeeded. Mar 17 18:13:11 tu-mn systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 18:13:11 tu-mn systemd[1]: shairport-sync.service: Succeeded. Mar 17 18:13:11 tu-mn systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 18:13:11 tu-mn systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 18:13:11 tu-mn sudo[1177]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:11 tu-mn sudo[1179]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:11 tu-mn sudo[1181]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:11 tu-mn volumio[964]: info: Shairport-Sync Started Mar 17 18:13:11 tu-mn volumio[964]: Error adding Membership: Error: addMembership EINVAL Mar 17 18:13:11 tu-mn volumio[964]: info: Shairport-Sync Started Mar 17 18:13:11 tu-mn volumio[964]: info: Shairport-Sync Started Mar 17 18:13:11 tu-mn go-librespot[1174]: time="2025-03-17T18:13:11+07:00" level=info msg="running go-librespot 0.2.0" Mar 17 18:13:11 tu-mn go-librespot[1174]: time="2025-03-17T18:13:11+07:00" level=debug msg="app state loaded" Mar 17 18:13:11 tu-mn go-librespot[1174]: time="2025-03-17T18:13:11+07:00" level=debug msg="stored credentials not found" Mar 17 18:13:11 tu-mn go-librespot[1174]: time="2025-03-17T18:13:11+07:00" level=info msg="api server listening on 127.0.0.1:9879" Mar 17 18:13:11 tu-mn volumio[964]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 17 18:13:11 tu-mn volumio[964]: info: CoreCommandRouter::volumioRetrievevolume Mar 17 18:13:11 tu-mn volumio[964]: info: CoreCommandRouter::volumioGetState Mar 17 18:13:11 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:11 tu-mn volumio[964]: info: CoreStateMachine::pushState Mar 17 18:13:11 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:11 tu-mn volumio[964]: info: CoreCommandRouter::volumioPushState Mar 17 18:13:12 tu-mn go-librespot[1174]: time="2025-03-17T18:13:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Mar 17 18:13:12 tu-mn go-librespot[1174]: time="2025-03-17T18:13:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Mar 17 18:13:12 tu-mn go-librespot[1174]: time="2025-03-17T18:13:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Mar 17 18:13:12 tu-mn volumio-remote-updater[461]: [2025-03-17 18:13:12] [connect] Successful connection Mar 17 18:13:12 tu-mn volumio-remote-updater[461]: [2025-03-17 18:13:12] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742209992 101 Mar 17 18:13:12 tu-mn go-librespot[1174]: time="2025-03-17T18:13:12+07:00" level=info msg="zeroconf server listening on port 44861" Mar 17 18:13:12 tu-mn volumio[964]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 2 Mar 17 18:13:12 tu-mn mpd[1138]: Mar 17 18:13 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 18:13:12 tu-mn systemd[1]: Started Music Player Daemon. Mar 17 18:13:12 tu-mn volumio[964]: Upnp client error: Error: This socket has been ended by the other party Mar 17 18:13:12 tu-mn sudo[1103]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:12 tu-mn sudo[1114]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:13 tu-mn volumio[964]: error: MPD error: The expression evaluated to a falsy value: Mar 17 18:13:13 tu-mn volumio[964]: assert.ok(self.idling) Mar 17 18:13:13 tu-mn volumio[964]: error: The expression evaluated to a falsy value: Mar 17 18:13:13 tu-mn volumio[964]: assert.ok(self.idling) Mar 17 18:13:13 tu-mn volumio[964]: error: updateQueue error: null Mar 17 18:13:13 tu-mn volumio[964]: info: MPD running with PID1138 Mar 17 18:13:13 tu-mn volumio[964]: ,establishing connection Mar 17 18:13:13 tu-mn volumio[964]: error: updateQueue error: null Mar 17 18:13:13 tu-mn volumio[964]: info: Completed starting Core Plugins Mar 17 18:13:13 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:13:13 tu-mn volumio[964]: info: ----- MyVolumio plugins startup ---- Mar 17 18:13:13 tu-mn volumio[964]: info: ------------------------------------------- Mar 17 18:13:13 tu-mn volumio[964]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 17 18:13:13 tu-mn sudo[1083]: volumio : unable to resolve host tu-mn Mar 17 18:13:13 tu-mn sudo[1083]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:13 tu-mn sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 17 18:13:13 tu-mn sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 18:13:13 tu-mn sudo[1083]: pam_unix(sudo:session): session closed for user root Mar 17 18:13:13 tu-mn volumio[1108]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 17 18:13:13 tu-mn volumio[964]: info: go-librespot daemon successfully initialized Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="obtained new client token: AAAL0EhVS/2jXJdxOVrOpEKA57iztt8QuWDKz+J7dNKHSpvXN5bf2Ka0hv8iWybboTpGYdImBlS98ljNkI0CSOTvBP1+Siv5G+6r+qMQvaXu7L5i/G6QyhVWLbagfljBo+1dZUcR0cMIS0JlZTJAKCh/k10wiuoAHXbpCThdnJBP1dQkfsGaUn+Lpu3MDlMO4Y2RwR/dmETveDhe9f+hzlrosqcEANMhTx+Ch/YBx7KZjMsz6c1sM5kBhQ==" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="completed keyexchange" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="completed challenge" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=info msg="authenticated AP as 31cjvu4vq7fw7kumdldlg4t7323m" Mar 17 18:13:16 tu-mn volumio[964]: info: Initializing connection to go-librespot Websocket Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="new websocket client" Mar 17 18:13:16 tu-mn volumio[964]: info: Connection to go-librespot Websocket established Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=info msg="authenticated Login5 as 31cjvu4vq7fw7kumdldlg4t7323m" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=info msg="accepted zeroconf user 31cjvu4vq7fw7kumdldlg4t7323m from vivo 1901" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="dealer connection opened" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=trace msg="starting accesspoint recv loop" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=trace msg="starting dealer recv loop" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=trace msg="received accesspoint ping" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 17 18:13:16 tu-mn go-librespot[1174]: time="2025-03-17T18:13:16+07:00" level=debug msg="received connection id: Yzg3MzM1OTQtN2VlYS00MWU1LTgxMTQtZWEzYzM4OTA3M2Q0K2RlYWxlcit0Y3A6Ly8wYWM5NDg2My5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArRjg3NTU5MDYyMjY4MDQyRDlFN0M3MDRDOEJBNTU5REIyOTMzQ0U2MDk3RjU0QUREM0I2QUNERDM5MkVGRkQ5OA==" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=trace msg="received accesspoint pong ack" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="put connect state because NEW_DEVICE" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="handling transfer player command from 5f143e1c5ddcf5051c24ad5cb461168eb1341060" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="resolved context of track" uri="spotify:playlist:1j5yknD8IUEldjEicVfnLJ" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=trace msg="fetched new page 0 with 56 items (list: 56)" uri="spotify:playlist:1j5yknD8IUEldjEicVfnLJ" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="loading track (paused: false, position: 257947ms)" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=trace msg="emitting websocket event: will_play" Mar 17 18:13:17 tu-mn volumio[964]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP","play_origin":"playlist"}} Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="selected format OGG_VORBIS_320 (eb869d30426c09527953414c09b422944943b7fa)" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:17 tu-mn go-librespot[1174]: time="2025-03-17T18:13:17+07:00" level=debug msg="requested aes key for file eb869d30426c09527953414c09b422944943b7fa, gid: 2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn volumio[964]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="fetched first chunk of 14, total size is 6917788 bytes" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 339" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1775" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="fetched chunk 13/13, size: 102044" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=trace msg="seek to 223921ms (diff: 164ms, samples: 9874916, bytes: 6907879)" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:18 tu-mn go-librespot[1174]: time="2025-03-17T18:13:18+07:00" level=debug msg="created new output device" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 100000 us, period size = 4410 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 5 frames" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=info msg="loaded track \"Ngồi Tựa Mạn Thuyền (Official Version)\" (paused: false, position: 257947ms, duration: 223921ms, prefetched: false)" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=trace msg="emitting websocket event: metadata" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="prefetching next track" uri="spotify:track:4VJzosndzF35wkBQ0J7zjJ" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=trace msg="emitting websocket event: active" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="sending successful reply for dealer request" Mar 17 18:13:19 tu-mn volumio[964]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP","name":"Ngồi Tựa Mạn Thuyền (Official Version)","artist_names":["Hương Ly","LY Media"],"album_name":"Tuyển Tập Những Ca Khúc Hot Tik Tok Của Hương Ly #2","album_cover_url":"https://i.scdn.co/image/ab67616d00001e0244c1617b5e2a029377942b46","position":257947,"duration":223921,"release_date":"year:2022 month:4 day:12","track_number":1,"disc_number":1}} Mar 17 18:13:19 tu-mn volumio[964]: SPOTIFY: received: {"type":"active","data":null} Mar 17 18:13:19 tu-mn volumio[964]: info: Aligning Spotify Volume to Volumio Volume Mar 17 18:13:19 tu-mn volumio[964]: info: CoreCommandRouter::volumioGetState Mar 17 18:13:19 tu-mn volumio[964]: info: CorePlayQueue::getTrack 0 Mar 17 18:13:19 tu-mn volumio[964]: info: Setting Spotify Volume from Volumio: 100 Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="selected format OGG_VORBIS_320 (b45622b707026bc979fc954eaf6f93682fa193a3)" uri="spotify:track:4VJzosndzF35wkBQ0J7zjJ" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="requested aes key for file b45622b707026bc979fc954eaf6f93682fa193a3, gid: 4VJzosndzF35wkBQ0J7zjJ" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=trace msg="emitting websocket event: playing" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=info msg="playback was transferred to vivo 1901" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=trace msg="closed output device because of stop command" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 17 18:13:19 tu-mn volumio[964]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2wYGW8Ok0Ntw0jQIyz4nwP","play_origin":"playlist"}} Mar 17 18:13:19 tu-mn volumio[964]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 18:13:19 tu-mn volumio[964]: TypeError: Cannot read property 'service' of undefined Mar 17 18:13:19 tu-mn volumio[964]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:340:50) Mar 17 18:13:19 tu-mn volumio[964]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:266:18) Mar 17 18:13:19 tu-mn volumio[964]: at WebSocket.message (/data/plugins/music_service/spop/index.js:193:14) Mar 17 18:13:19 tu-mn volumio[964]: at WebSocket.emit (events.js:315:20) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.emit (events.js:315:20) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Mar 17 18:13:19 tu-mn volumio[964]: at writeOrBuffer (internal/streams/writable.js:358:12) Mar 17 18:13:19 tu-mn volumio[964]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Mar 17 18:13:19 tu-mn volumio[964]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Mar 17 18:13:19 tu-mn volumio[964]: at Socket.emit (events.js:315:20) Mar 17 18:13:19 tu-mn volumio[964]: at addChunk (internal/streams/readable.js:309:12) Mar 17 18:13:19 tu-mn volumio[964]: at readableAddChunk (internal/streams/readable.js:284:9) Mar 17 18:13:19 tu-mn volumio[964]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=debug msg="put connect state inactive" Mar 17 18:13:19 tu-mn go-librespot[1174]: time="2025-03-17T18:13:19+07:00" level=trace msg="emitting websocket event: inactive" Mar 17 18:13:19 tu-mn go-librespot[1174]: panic: runtime error: invalid memory address or nil pointer dereference Mar 17 18:13:19 tu-mn go-librespot[1174]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x14 pc=0x5ec094] Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 38 gp=0x235e128 m=0 mp=0xbcc020 [running]: Mar 17 18:13:19 tu-mn go-librespot[1174]: panic({0x67bd18, 0xb7c2a0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x250b824 sp=0x250b7d0 pc=0x58b00 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.panicmem(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/panic.go:261 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.sigpanic() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x250b854 sp=0x250b824 pc=0x74aec Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*AppPlayer).handlePlayerEvent(0x20c9d60, {0x7d7634, 0xbf6000}, 0x250ba54) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/controls.go:108 +0x2d4 fp=0x250b8f4 sp=0x250b858 pc=0x5ec094 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*AppPlayer).Run(0x20c9d60, {0x7d7634, 0xbf6000}, 0x2354200) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/player.go:593 +0x740 fp=0x250bfd8 sp=0x250b8f4 pc=0x5fb2f0 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*App).withAppPlayer.func3.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:352 +0x40 fp=0x250bfec sp=0x250bfd8 pc=0x5f3494 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x250bfec sp=0x250bfec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:352 +0x50c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 1 gp=0x2002128 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2286d2c sp=0x2286d18 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x2286e2c, 0x2231df8, 0x0, 0x0, 0x2, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2286dcc sp=0x2286d2c pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x21caba0, 0x2286eb8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x2286e70 sp=0x2286dcc pc=0x55dd34 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*App).withAppPlayer(0x20987c0, {0x7d7634, 0xbf6000}, 0x2095400) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:316 +0x514 fp=0x2286ef8 sp=0x2286e70 pc=0x5f2dcc Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*App).Zeroconf(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:168 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.main() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:610 +0x918 fp=0x2286fa8 sp=0x2286ef8 pc=0x5f5d54 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.main() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2286fec sp=0x2286fa8 pc=0x5ca38 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2286fec sp=0x2286fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 18 gp=0x2084248 m=nil [force gc (idle)]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407d4 sp=0x20407c0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goparkunlock(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:408 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.forcegchelper() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x20407ec sp=0x20407d4 pc=0x5ce04 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.init.5 in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:314 +0x1c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 19 gp=0x2084368 m=nil [GC sweep wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fc4 sp=0x2040fb0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goparkunlock(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:408 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.bgsweep(0x2092000) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x2040fe4 sp=0x2040fc4 pc=0x45750 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcenable.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x35870 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcenable in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 20 gp=0x2084488 m=nil [GC scavenge wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20417b4 sp=0x20417a0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goparkunlock(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:408 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.(*scavengerState).park(0xbcb508) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x20417c8 sp=0x20417b4 pc=0x42a70 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.bgscavenge(0x2092000) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x20417e4 sp=0x20417c8 pc=0x431a8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcenable.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x20417ec sp=0x20417e4 pc=0x3581c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcenable in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 2 gp=0x2002c68 m=nil [finalizer wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044f8c sp=0x2044f78 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.runfinq() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x2044fec sp=0x2044f8c pc=0x3465c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2044fec sp=0x2044fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.createfing in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 23 gp=0x2085568 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563ff18, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042528 sp=0x2042514 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563ff08, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2042540 sp=0x2042528 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563ff08, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2042554 sp=0x2042540 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x20c8e28, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2042568 sp=0x2042554 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Accept(0x20c8e10) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x20425b0 sp=0x2042568 pc=0x10eb44 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).accept(0x20c8e10) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2042618 sp=0x20425b0 pc=0x17e32c Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPListener).accept(0x20ac4c8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2042630 sp=0x2042618 pc=0x19642c Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPListener).Accept(0x20ac4c8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x204264c sp=0x2042630 pc=0x195498 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*onceCloseListener).Accept(0x20749c0) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x34 fp=0x2042664 sp=0x204264c pc=0x362680 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Server).Serve(0x207c508, {0x7d6e18, 0x20ac4c8}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x20426fc sp=0x2042664 pc=0x340d3c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.Serve(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2794 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*ConcreteApiServer).serve(0x20c46c0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x20427e4 sp=0x20426fc pc=0x5e8bc0 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.NewApiServer.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x20427ec sp=0x20427e4 pc=0x5e7a90 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by main.NewApiServer in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/api_server.go:289 +0x310 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 115 gp=0x2085688 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2054eb0 sp=0x2054e9c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x2054fac, 0x2054f78, 0x0, 0x0, 0x2, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2054f50 sp=0x2054eb0 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).writeLoop(0x20d80b8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x2054fe4 sp=0x2054f50 pc=0x357748 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).dialConn.gowrap3() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x2054fec sp=0x2054fe4 pc=0x354554 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2054fec sp=0x2054fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*Transport).dialConn in goroutine 87 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 53 gp=0x20857a8 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fd38, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2284e84 sp=0x2284e70 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fd28, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2284e9c sp=0x2284e84 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fd28, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2284eb0 sp=0x2284e9c pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x21e3a08, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2284ec4 sp=0x2284eb0 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).RawRead(0x21e39f0, 0x21b8960) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2284ef0 sp=0x2284ec4 pc=0x10f748 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*rawConn).Read(0x21b35d8, 0x21b8960) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2284f10 sp=0x2284ef0 pc=0x190854 Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2195df0, 0x21b8930, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2284f40 sp=0x2284f10 pc=0x4fe72c Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2501c58, {0x23bc000, 0x10000, 0x10000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x2284f9c sp=0x2284f40 pc=0x502714 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/grandcat/zeroconf.(*Server).recv4(0x2276f00, 0x2501c50) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2284fe0 sp=0x2284f9c pc=0x558fa4 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2284fec sp=0x2284fe0 pc=0x558c5c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2284fec sp=0x2284fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 4 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 35 gp=0x20858c8 m=nil [GC worker (idle)]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736720, 0x202a360, 0x1a, 0xa, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2041f90 sp=0x2041f7c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcBgMarkWorker() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2041fec sp=0x2041f90 pc=0x38498 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2041fec sp=0x2041fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 29 gp=0x2085b08 m=nil [GC worker (idle)]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736720, 0x23e2960, 0x1a, 0xa, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042f90 sp=0x2042f7c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcBgMarkWorker() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2042fec sp=0x2042f90 pc=0x38498 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2042fec sp=0x2042fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 3 gp=0x2003208 m=nil [GC worker (idle)]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736720, 0x23e2978, 0x1a, 0xa, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2045790 sp=0x204577c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcBgMarkWorker() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20457ec sp=0x2045790 pc=0x38498 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20457ec sp=0x20457ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 36 gp=0x21885a8 m=nil [GC worker (idle)]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736720, 0x2488000, 0x1a, 0xa, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044790 sp=0x204477c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gcBgMarkWorker() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20447ec sp=0x2044790 pc=0x38498 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20447ec sp=0x20447ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 51 gp=0x20847e8 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fe78, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2287ad4 sp=0x2287ac0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fe68, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2287aec sp=0x2287ad4 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fe68, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2287b00 sp=0x2287aec pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x20c8fb8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2287b14 sp=0x2287b00 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x20c8fa0, {0x224c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2287b5c sp=0x2287b14 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x20c8fa0, {0x224c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2287b88 sp=0x2287b5c pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x21b2158, {0x224c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2287bb4 sp=0x2287b88 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x21b2158, {0x224c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2287bd4 sp=0x2287bb4 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x2195d20, {0x224c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2287c00 sp=0x2287bd4 pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x218a3cc, {0x7d4554, 0x2195d20}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2287c3c sp=0x2287c00 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x218a248, {0x7d373c, 0x21b2158}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2287c64 sp=0x2287c3c pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x218a248, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2287dc8 sp=0x2287c64 pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x218a248, {0x214a000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2287df8 sp=0x2287dc8 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Read(0x2148270, {0x20ba1a4, 0x9, 0x9}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2287e1c sp=0x2287df8 pc=0x2bdf6c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadAtLeast({0x7d3930, 0x2148270}, {0x20ba1a4, 0x9, 0x9}, 0x9) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2287e48 sp=0x2287e1c pc=0x104280 Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadFull(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:354 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.http2readFrameHeader({0x20ba1a4, 0x9, 0x9}, {0x7d3930, 0x2148270}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2287e70 sp=0x2287e48 pc=0x2f6e68 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Framer).ReadFrame(0x20ba180) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2287eec sp=0x2287e70 pc=0x2f7600 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2clientConnReadLoop).run(0x2287fdc) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2287fa4 sp=0x2287eec pc=0x31be1c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2ClientConn).readLoop(0x211c708) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2287fe4 sp=0x2287fa4 pc=0x31b310 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2287fec sp=0x2287fe4 pc=0x31435c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2287fec sp=0x2287fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*http2Transport).newClientConn in goroutine 50 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 6 gp=0x2003688 m=nil [chan receive]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x7366f4, 0x2092130, 0xe, 0x7, 0x2) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215cf48 sp=0x215cf34 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.chanrecv(0x2092100, 0x215cfd0, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x215cf84 sp=0x215cf48 pc=0x1ff80 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.chanrecv2(0x2092100, 0x215cfd0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x215cf98 sp=0x215cf84 pc=0x1fac4 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*App).withAppPlayer.func1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:264 +0x68 fp=0x215cfec sp=0x215cf98 pc=0x5f3964 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215cfec sp=0x215cfec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by main.(*App).withAppPlayer in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:261 +0x424 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 7 gp=0x20037a8 m=nil [runnable]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x7366f4, 0x20920f0, 0xe, 0x7, 0x2) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215d738 sp=0x215d724 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.chanrecv(0x20920c0, 0x215d7e0, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x215d774 sp=0x215d738 pc=0x1ff80 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.chanrecv2(0x20920c0, 0x215d7e0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x215d788 sp=0x215d774 pc=0x1fac4 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*App).withAppPlayer.func2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:280 +0x6c fp=0x215d7ec sp=0x215d788 pc=0x5f352c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215d7ec sp=0x215d7ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by main.(*App).withAppPlayer in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/main.go:277 +0x4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 54 gp=0x2084b48 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fc98, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2043e5c sp=0x2043e48 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fc88, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2043e74 sp=0x2043e5c pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fc88, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2043e88 sp=0x2043e74 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x21e3a58, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2043e9c sp=0x2043e88 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).RawRead(0x21e3a40, 0x2148360) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2043ec8 sp=0x2043e9c pc=0x10f748 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*rawConn).Read(0x21b35f8, 0x2148360) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2043ee8 sp=0x2043ec8 pc=0x190854 Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2195e00, 0x2148330, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2043f18 sp=0x2043ee8 pc=0x4fe72c Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Mar 17 18:13:19 tu-mn go-librespot[1174]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2501c88, {0x228e000, 0x10000, 0x10000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x2043f9c sp=0x2043f18 pc=0x50609c Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/grandcat/zeroconf.(*Server).recv6(0x2276f00, 0x2501c80) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2043fe0 sp=0x2043f9c pc=0x559198 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2043fec sp=0x2043fe0 pc=0x558c00 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2043fec sp=0x2043fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 4 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 8 gp=0x20038c8 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fdd8, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215dde8 sp=0x215ddd4 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fdc8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x215de00 sp=0x215dde8 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fdc8, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x215de14 sp=0x215de00 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x21e39b8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x215de28 sp=0x215de14 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Accept(0x21e39a0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x215de70 sp=0x215de28 pc=0x10eb44 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).accept(0x21e39a0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x215ded8 sp=0x215de70 pc=0x17e32c Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPListener).accept(0x20273e0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x215def0 sp=0x215ded8 pc=0x19642c Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPListener).Accept(0x20273e0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x215df0c sp=0x215def0 pc=0x195498 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*onceCloseListener).Accept(0x2185d20) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x34 fp=0x215df24 sp=0x215df0c pc=0x362680 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Server).Serve(0x24aa328, {0x7d6e18, 0x20273e0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x215dfbc sp=0x215df24 pc=0x340d3c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.Serve(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2794 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/zeroconf/zeroconf.go:267 +0x8c fp=0x215dfec sp=0x215dfbc pc=0x55de30 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215dfec sp=0x215dfec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/zeroconf/zeroconf.go:267 +0x160 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 16 gp=0x2188a28 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563f978, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x228387c sp=0x2283868 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563f968, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2283894 sp=0x228387c pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563f968, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x22838a8 sp=0x2283894 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x23fa0b8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x22838bc sp=0x22838a8 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x23fa0a0, {0x226e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2283904 sp=0x22838bc pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x23fa0a0, {0x226e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2283930 sp=0x2283904 pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x23db368, {0x226e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x228395c sp=0x2283930 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x23db368, {0x226e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x228397c sp=0x228395c pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.(*multiReader).Read(0x239bce0, {0x226e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x22839a8 sp=0x228397c pc=0x1054e8 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).fill(0x21b88d0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x22839cc sp=0x22839a8 pc=0x2bd8e0 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).ReadByte(0x21b88d0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x22839d8 sp=0x22839cc pc=0x2be164 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.readFrameHeader(0x21b88d0, {0x213e3f8, 0x8, 0x8}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2283a18 sp=0x22839d8 pc=0x470950 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x213e3c8, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2283ad8 sp=0x2283a18 pc=0x4735b4 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).readLoop(0x213e3c8, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2283b9c sp=0x2283ad8 pc=0x47309c Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).reader(0x213e3c8, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2283c28 sp=0x2283b9c pc=0x4742b0 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).Read(0x213e3c8, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2283c50 sp=0x2283c28 pc=0x472980 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x2283ccc sp=0x2283c50 pc=0x5e9490 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.HandlerFunc.ServeHTTP(0x2030248, {0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2283cdc sp=0x2283ccc pc=0x33d320 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*ServeMux).ServeHTTP(0x212a200, {0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2283d18 sp=0x2283cdc pc=0x33f180 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2283d40 sp=0x2283d18 pc=0x5e8e90 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.HandlerFunc.ServeHTTP(0x200e2f0, {0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2283d50 sp=0x2283d40 pc=0x33d320 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.serverHandler.ServeHTTP({0x207c508}, {0x7d6f4c, 0x24abf48}, 0x24abea8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2283d6c sp=0x2283d50 pc=0x340910 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*conn).serve(0x20c5200, {0x7d7654, 0x20261e0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2283fdc sp=0x2283d6c pc=0x33bb60 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Server).Serve.gowrap3() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2283fec sp=0x2283fdc pc=0x3411b4 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2283fec sp=0x2283fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*Server).Serve in goroutine 23 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/server.go:3285 +0x468 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 83 gp=0x20039e8 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20436a8 sp=0x2043694 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x20437a4, 0x204376c, 0x0, 0x0, 0x5, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2043748 sp=0x20436a8 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x213e3c8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x20437e4 sp=0x2043748 pc=0x46d7fc Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.newConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x20437ec sp=0x20437e4 pc=0x46d3f0 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20437ec sp=0x20437ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by nhooyr.io/websocket.newConn in goroutine 16 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 118 gp=0x2084ea8 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563f798, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2262afc sp=0x2262ae8 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563f788, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2262b14 sp=0x2262afc pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563f788, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2262b28 sp=0x2262b14 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x23fa5b8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2262b3c sp=0x2262b28 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x23fa5a0, {0x229e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2262b84 sp=0x2262b3c pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x23fa5a0, {0x229e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2262bb0 sp=0x2262b84 pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x23da5d0, {0x229e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2262bdc sp=0x2262bb0 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x23da5d0, {0x229e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2262bfc sp=0x2262bdc pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x234e1c0, {0x229e000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2262c28 sp=0x2262bfc pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x23f514c, {0x7d4554, 0x234e1c0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2262c64 sp=0x2262c28 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x23f4fc8, {0x7d373c, 0x23da5d0}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2262c8c sp=0x2262c64 pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x23f4fc8, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2262df0 sp=0x2262c8c pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x23f4fc8, {0x230a000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2262e20 sp=0x2262df0 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).Read(0x249a008, {0x230a000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x2262e54 sp=0x2262e20 pc=0x3550f8 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).fill(0x2148d20) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2262e78 sp=0x2262e54 pc=0x2bd8e0 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Peek(0x2148d20, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x2262e84 sp=0x2262e78 pc=0x2bda64 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).readLoop(0x249a008) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x2262fe4 sp=0x2262e84 pc=0x355c88 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).dialConn.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x2262fec sp=0x2262fe4 pc=0x3545a8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2262fec sp=0x2262fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*Transport).dialConn in goroutine 104 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 72 gp=0x21890e8 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215c6a8 sp=0x215c694 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x215c7a4, 0x215c76c, 0x0, 0x0, 0x5, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x215c748 sp=0x215c6a8 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x213e508) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x215c7e4 sp=0x215c748 pc=0x46d7fc Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.newConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x215c7ec sp=0x215c7e4 pc=0x46d3f0 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215c7ec sp=0x215c7ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by nhooyr.io/websocket.newConn in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 73 gp=0x2189208 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fab8, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2280d14 sp=0x2280d00 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563faa8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2280d2c sp=0x2280d14 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563faa8, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2280d40 sp=0x2280d2c pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x22b6068, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2280d54 sp=0x2280d40 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x22b6050, {0x232c220, 0x3, 0x3}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2280d9c sp=0x2280d54 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x22b6050, {0x232c220, 0x3, 0x3}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2280dc8 sp=0x2280d9c pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x21b3ae0, {0x232c220, 0x3, 0x3}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2280df4 sp=0x2280dc8 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x21b3ae0, {0x232c220, 0x3, 0x3}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2280e14 sp=0x2280df4 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadAtLeast({0x7d373c, 0x21b3ae0}, {0x232c220, 0x3, 0x3}, 0x3) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2280e40 sp=0x2280e14 pc=0x104280 Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadFull(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:354 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2098ac0, {0x7d7634, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/shannon.go:124 +0x3a0 fp=0x2280f04 sp=0x2280e40 pc=0x488a10 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x219be48) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:276 +0x68 fp=0x2280fe4 sp=0x2280f04 pc=0x485188 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:260 +0x28 fp=0x2280fec sp=0x2280fe4 pc=0x4850f4 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2280fec sp=0x2280fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:260 +0x98 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 69 gp=0x2189448 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fb58, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2055ad4 sp=0x2055ac0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fb48, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2055aec sp=0x2055ad4 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fb48, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2055b00 sp=0x2055aec pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x240b828, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2055b14 sp=0x2055b00 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x240b810, {0x2426000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2055b5c sp=0x2055b14 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x240b810, {0x2426000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2055b88 sp=0x2055b5c pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x20942c0, {0x2426000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2055bb4 sp=0x2055b88 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x20942c0, {0x2426000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2055bd4 sp=0x2055bb4 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x239a5f0, {0x2426000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2055c00 sp=0x2055bd4 pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x20b6f0c, {0x7d4554, 0x239a5f0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2055c3c sp=0x2055c00 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x20b6d88, {0x7d373c, 0x20942c0}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2055c64 sp=0x2055c3c pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x20b6d88, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2055dc8 sp=0x2055c64 pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x20b6d88, {0x242c000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2055df8 sp=0x2055dc8 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Read(0x2428060, {0x212a5a4, 0x9, 0x9}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2055e1c sp=0x2055df8 pc=0x2bdf6c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadAtLeast({0x7d3930, 0x2428060}, {0x212a5a4, 0x9, 0x9}, 0x9) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2055e48 sp=0x2055e1c pc=0x104280 Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadFull(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:354 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.http2readFrameHeader({0x212a5a4, 0x9, 0x9}, {0x7d3930, 0x2428060}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2055e70 sp=0x2055e48 pc=0x2f6e68 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Framer).ReadFrame(0x212a580) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2055eec sp=0x2055e70 pc=0x2f7600 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2clientConnReadLoop).run(0x2055fdc) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2055fa4 sp=0x2055eec pc=0x31be1c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2ClientConn).readLoop(0x242a008) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2055fe4 sp=0x2055fa4 pc=0x31b310 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2055fec sp=0x2055fe4 pc=0x31435c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2055fec sp=0x2055fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*http2Transport).newClientConn in goroutine 68 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 85 gp=0x23d5568 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fa18, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205aad4 sp=0x205aac0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fa08, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x205aaec sp=0x205aad4 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fa08, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x205ab00 sp=0x205aaec pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x240b968, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x205ab14 sp=0x205ab00 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x240b950, {0x229f000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x205ab5c sp=0x205ab14 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x240b950, {0x229f000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x205ab88 sp=0x205ab5c pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x23db348, {0x229f000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x205abb4 sp=0x205ab88 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x23db348, {0x229f000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x205abd4 sp=0x205abb4 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x234e010, {0x229f000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x205ac00 sp=0x205abd4 pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x23f484c, {0x7d4554, 0x234e010}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x205ac3c sp=0x205ac00 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x23f46c8, {0x7d373c, 0x23db348}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x205ac64 sp=0x205ac3c pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x23f46c8, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x205adc8 sp=0x205ac64 pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x23f46c8, {0x24c8000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x205adf8 sp=0x205adc8 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Read(0x24c4450, {0x21ce924, 0x9, 0x9}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x205ae1c sp=0x205adf8 pc=0x2bdf6c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadAtLeast({0x7d3930, 0x24c4450}, {0x21ce924, 0x9, 0x9}, 0x9) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x205ae48 sp=0x205ae1c pc=0x104280 Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadFull(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:354 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.http2readFrameHeader({0x21ce924, 0x9, 0x9}, {0x7d3930, 0x24c4450}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x205ae70 sp=0x205ae48 pc=0x2f6e68 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Framer).ReadFrame(0x21ce900) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x205aeec sp=0x205ae70 pc=0x2f7600 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2clientConnReadLoop).run(0x205afdc) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x205afa4 sp=0x205aeec pc=0x31be1c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2ClientConn).readLoop(0x218d008) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x205afe4 sp=0x205afa4 pc=0x31b310 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x205afec sp=0x205afe4 pc=0x31435c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x205afec sp=0x205afec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*http2Transport).newClientConn in goroutine 84 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 37 gp=0x235e008 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2264e74 sp=0x2264e60 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x2264f74, 0x2264f3c, 0x0, 0x0, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2264f14 sp=0x2264e74 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x2312480) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/player/player.go:181 +0x1fc fp=0x2264fe4 sp=0x2264f14 pc=0x4cbbd8 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/player/player.go:163 +0x28 fp=0x2264fec sp=0x2264fe4 pc=0x4cb7b8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2264fec sp=0x2264fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/player/player.go:163 +0x1c8 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 48 gp=0x235e368 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2285cb0 sp=0x2285c9c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x2285e14, 0x2285d80, 0x0, 0x0, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2285d50 sp=0x2285cb0 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x2320360) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/provider.go:63 +0x118 fp=0x2285fe4 sp=0x2285d50 pc=0x48c7fc Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/provider.go:53 +0x28 fp=0x2285fec sp=0x2285fe4 pc=0x48d524 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2285fec sp=0x2285fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/provider.go:53 +0x60 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 114 gp=0x235e488 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563f838, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2281afc sp=0x2281ae8 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563f828, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2281b14 sp=0x2281afc pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563f828, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2281b28 sp=0x2281b14 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x2394428, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2281b3c sp=0x2281b28 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x2394410, {0x22a4000, 0xa000, 0xa000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2281b84 sp=0x2281b3c pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x2394410, {0x22a4000, 0xa000, 0xa000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2281bb0 sp=0x2281b84 pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x23da000, {0x22a4000, 0xa000, 0xa000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2281bdc sp=0x2281bb0 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x23da000, {0x22a4000, 0xa000, 0xa000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2281bfc sp=0x2281bdc pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x200e260, {0x22a4000, 0xa000, 0xa000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2281c28 sp=0x2281bfc pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x230dc8c, {0x7d4554, 0x200e260}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2281c64 sp=0x2281c28 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x230db08, {0x7d373c, 0x23da000}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2281c8c sp=0x2281c64 pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x230db08, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2281df0 sp=0x2281c8c pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x230db08, {0x2140000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2281e20 sp=0x2281df0 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).Read(0x20d80b8, {0x2140000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x2281e54 sp=0x2281e20 pc=0x3550f8 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).fill(0x24c5260) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2281e78 sp=0x2281e54 pc=0x2bd8e0 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Peek(0x24c5260, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x2281e84 sp=0x2281e78 pc=0x2bda64 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).readLoop(0x20d80b8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x2281fe4 sp=0x2281e84 pc=0x355c88 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).dialConn.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x2281fec sp=0x2281fe4 pc=0x3545a8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2281fec sp=0x2281fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*Transport).dialConn in goroutine 87 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 74 gp=0x235e7e8 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215eeb8 sp=0x215eea4 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x215efd4, 0x215ef7c, 0x0, 0x0, 0x2, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x215ef58 sp=0x215eeb8 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x219be48) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:355 +0x8c fp=0x215efe4 sp=0x215ef58 pc=0x4859a8 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:264 +0x28 fp=0x215efec sp=0x215efe4 pc=0x4850a0 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215efec sp=0x215efec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/ap/ap.go:264 +0x15c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 75 gp=0x235e908 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563f8d8, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2507940 sp=0x250792c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563f8c8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2507958 sp=0x2507940 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563f8c8, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x250796c sp=0x2507958 pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x23fa248, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2507980 sp=0x250796c pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x23fa230, {0x242d000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x25079c8 sp=0x2507980 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x23fa230, {0x242d000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x25079f4 sp=0x25079c8 pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x23da020, {0x242d000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2507a20 sp=0x25079f4 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x23da020, {0x242d000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2507a40 sp=0x2507a20 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x2091150, {0x242d000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2507a6c sp=0x2507a40 pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x230d5cc, {0x7d4554, 0x2091150}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2507aa8 sp=0x2507a6c pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x230d448, {0x7d373c, 0x23da020}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2507ad0 sp=0x2507aa8 pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x230d448, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2507c34 sp=0x2507ad0 pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x230d448, {0x244a000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2507c64 sp=0x2507c34 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*readWriteCloserBody).Read(0x234e870, {0x244a000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2429 +0xec fp=0x2507c84 sp=0x2507c64 pc=0x35762c Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).fill(0x2428000) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2507ca8 sp=0x2507c84 pc=0x2bd8e0 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).ReadByte(0x2428000) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x2507cb4 sp=0x2507ca8 pc=0x2be164 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.readFrameHeader(0x2428000, {0x213e538, 0x8, 0x8}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2507cf4 sp=0x2507cb4 pc=0x470950 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x213e508, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2507db4 sp=0x2507cf4 pc=0x4735b4 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).readLoop(0x213e508, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2507e78 sp=0x2507db4 pc=0x47309c Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).reader(0x213e508, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2507f04 sp=0x2507e78 pc=0x4742b0 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).Reader(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Mar 17 18:13:19 tu-mn go-librespot[1174]: nhooyr.io/websocket.(*Conn).Read(0x213e508, {0x7d7614, 0xbf6000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2507f2c sp=0x2507f04 pc=0x472980 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x20d8168) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:188 +0x58 fp=0x2507fe4 sp=0x2507f2c pc=0x4e8b14 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:125 +0x28 fp=0x2507fec sp=0x2507fe4 pc=0x4ea5e8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2507fec sp=0x2507fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:125 +0x98 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 76 gp=0x235ea28 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2158698 sp=0x2158684 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x21587d4, 0x2158760, 0x0, 0x0, 0x2, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2158738 sp=0x2158698 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x20d8168) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:138 +0x8c fp=0x21587e4 sp=0x2158738 pc=0x4e868c Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:129 +0x28 fp=0x21587ec sp=0x21587e4 pc=0x4ea594 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21587ec sp=0x21587ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/dealer/dealer.go:129 +0x15c Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 98 gp=0x2003b08 m=nil [IO wait]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736854, 0x7563fbf8, 0x2, 0x2, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2266ad4 sp=0x2266ac0 pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.netpollblock(0x7563fbe8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2266aec sp=0x2266ad4 pc=0x547d4 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.runtime_pollWait(0x7563fbe8, 0x72) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2266b00 sp=0x2266aec pc=0x8f388 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).wait(0x240baf8, 0x72, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2266b14 sp=0x2266b00 pc=0x10a2d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*pollDesc).waitRead(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Mar 17 18:13:19 tu-mn go-librespot[1174]: internal/poll.(*FD).Read(0x240bae0, {0x24ca000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2266b5c sp=0x2266b14 pc=0x10b4c4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*netFD).Read(0x240bae0, {0x24ca000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2266b88 sp=0x2266b5c pc=0x17c438 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*conn).Read(0x2418e18, {0x24ca000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2266bb4 sp=0x2266b88 pc=0x18c618 Mar 17 18:13:19 tu-mn go-librespot[1174]: net.(*TCPConn).Read(0x2418e18, {0x24ca000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: :1 +0x44 fp=0x2266bd4 sp=0x2266bb4 pc=0x19f86c Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*atLeastReader).Read(0x2091230, {0x24ca000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2266c00 sp=0x2266bd4 pc=0x27c1ac Mar 17 18:13:19 tu-mn go-librespot[1174]: bytes.(*Buffer).ReadFrom(0x2122f0c, {0x7d4554, 0x2091230}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2266c3c sp=0x2266c00 pc=0x139b90 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readFromUntil(0x2122d88, {0x7d373c, 0x2418e18}, 0x5) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2266c64 sp=0x2266c3c pc=0x27c408 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecordOrCCS(0x2122d88, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2266dc8 sp=0x2266c64 pc=0x279b08 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).readRecord(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:588 Mar 17 18:13:19 tu-mn go-librespot[1174]: crypto/tls.(*Conn).Read(0x2122d88, {0x2137000, 0x1000, 0x1000}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2266df8 sp=0x2266dc8 pc=0x27f978 Mar 17 18:13:19 tu-mn go-librespot[1174]: bufio.(*Reader).Read(0x2001da0, {0x2330624, 0x9, 0x9}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2266e1c sp=0x2266df8 pc=0x2bdf6c Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadAtLeast({0x7d3930, 0x2001da0}, {0x2330624, 0x9, 0x9}, 0x9) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2266e48 sp=0x2266e1c pc=0x104280 Mar 17 18:13:19 tu-mn go-librespot[1174]: io.ReadFull(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/io/io.go:354 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.http2readFrameHeader({0x2330624, 0x9, 0x9}, {0x7d3930, 0x2001da0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2266e70 sp=0x2266e48 pc=0x2f6e68 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Framer).ReadFrame(0x2330600) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2266eec sp=0x2266e70 pc=0x2f7600 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2clientConnReadLoop).run(0x2266fdc) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2266fa4 sp=0x2266eec pc=0x31be1c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2ClientConn).readLoop(0x218d108) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2266fe4 sp=0x2266fa4 pc=0x31b310 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*http2Transport).newClientConn.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2266fec sp=0x2266fe4 pc=0x31435c Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2266fec sp=0x2266fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*http2Transport).newClientConn in goroutine 81 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 119 gp=0x2188008 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x215b6b0 sp=0x215b69c pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x215b7ac, 0x215b778, 0x0, 0x0, 0x2, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x215b750 sp=0x215b6b0 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).writeLoop(0x249a008) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x215b7e4 sp=0x215b750 pc=0x357748 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).dialConn.gowrap3() Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x215b7ec sp=0x215b7e4 pc=0x354554 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x215b7ec sp=0x215b7ec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by net/http.(*Transport).dialConn in goroutine 104 Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Mar 17 18:13:19 tu-mn go-librespot[1174]: goroutine 121 gp=0x2374368 m=nil [select]: Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2282610 sp=0x22825fc pc=0x5cfc8 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.selectgo(0x2282764, 0x22826d8, 0x0, 0x0, 0x6, 0x1) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x22826b0 sp=0x2282610 pc=0x70d80 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*persistConn).roundTrip(0x20d80b8, 0x2097140) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:2675 +0x87c fp=0x22827fc sp=0x22826b0 pc=0x3586bc Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).roundTrip(0xb84118, 0x22a0dc8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/transport.go:608 +0x858 fp=0x2282918 sp=0x22827fc pc=0x34d25c Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Transport).RoundTrip(0xb84118, 0x22a0dc8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/roundtrip.go:17 +0x24 fp=0x2282930 sp=0x2282918 pc=0x331598 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.send(0x22a0dc8, {0x7d3aac, 0xb84118}, {0x0, 0x0, 0x0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/client.go:259 +0x540 fp=0x2282a5c sp=0x2282930 pc=0x2ec2c0 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Client).send(0x2096cc0, 0x22a0dc8, {0x0, 0x0, 0x0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x2282aa0 sp=0x2282a5c pc=0x2ebbb4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Client).do(0x2096cc0, 0x22a0dc8) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/client.go:724 +0x8bc fp=0x2282bb0 sp=0x2282aa0 pc=0x2edfb4 Mar 17 18:13:19 tu-mn go-librespot[1174]: net/http.(*Client).Do(...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/net/http/client.go:590 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/audio.(*HttpChunkedReader).downloadChunk.func1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/chunked_reader.go:111 +0x290 fp=0x2282c0c sp=0x2282bb0 pc=0x48b158 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x2282cd8, {0x7d68d0, 0x238e4f8}, 0x0, {0x0, 0x0}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:88 +0xf0 fp=0x2282c8c sp=0x2282c0c pc=0x48d6f0 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/cenkalti/backoff/v4.RetryNotifyWithData[...](...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:54 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/cenkalti/backoff/v4.RetryWithData[...](0x2282cd8, {0x7d68d0, 0x238e4f8}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:43 +0x54 fp=0x2282cb8 sp=0x2282c8c pc=0x48d5d8 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/audio.(*HttpChunkedReader).downloadChunk(0x2428390, 0x0) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/chunked_reader.go:110 +0x84 fp=0x2282ce4 sp=0x2282cb8 pc=0x48ae88 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/audio.NewHttpChunkedReader({0x7d9b30, 0x2392a40}, 0x2096cc0, {0x21742c0, 0xaf}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/audio/chunked_reader.go:74 +0x16c fp=0x2282d50 sp=0x2282ce4 pc=0x48a770 Mar 17 18:13:19 tu-mn go-librespot[1174]: github.com/devgianlu/go-librespot/player.(*Player).NewStream(0x2312480, {0x7d7634, 0xbf6000}, 0x2096cc0, {{0x202e758, 0x5}, {0x232c030, 0x10, 0x10}}, 0x140, ...) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/player/player.go:489 +0x768 fp=0x2282e8c sp=0x2282d50 pc=0x4ccf38 Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*AppPlayer).prefetchNext(0x20c9d60) Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/controls.go:45 +0x254 fp=0x2282fe4 sp=0x2282e8c pc=0x5eb2ec Mar 17 18:13:19 tu-mn go-librespot[1174]: main.(*AppPlayer).schedulePrefetchNext.gowrap1() Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/controls.go:69 +0x28 fp=0x2282fec sp=0x2282fe4 pc=0x5ebd94 Mar 17 18:13:19 tu-mn go-librespot[1174]: runtime.goexit({}) Mar 17 18:13:19 tu-mn go-librespot[1174]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2282fec sp=0x2282fec pc=0x93c4c Mar 17 18:13:19 tu-mn go-librespot[1174]: created by main.(*AppPlayer).schedulePrefetchNext in goroutine 38 Mar 17 18:13:19 tu-mn go-librespot[1174]: /src/cmd/daemon/controls.go:69 +0x1c8 Mar 17 18:13:19 tu-mn go-librespot[1174]: Aborted Mar 17 18:13:19 tu-mn systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Mar 17 18:13:19 tu-mn systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Mar 17 18:13:20 tu-mn sudo[1253]: volumio : unable to resolve host tu-mn Mar 17 18:13:20 tu-mn sudo[1253]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Mar 17 18:13:20 tu-mn sudo[1253]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 18:12 Mar 17 18:13:20 tu-mn sudo[1253]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"