-- Logs begin at Thu 2019-02-14 11:12:00 CET, end at Wed 2024-10-30 22:10:05 CET. -- Oct 30 22:09:02 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 30 22:09:02 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:04 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 30 22:09:04 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:06 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Oct 30 22:09:06 volumio volumio[962]: info: Getting webradio selection Oct 30 22:09:06 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 30 22:09:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 30 22:09:09 volumio volumio[962]: info: Discovery: Getting this device information Oct 30 22:09:09 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Oct 30 22:09:09 volumio volumio[962]: info: CorePlayQueue::getTrack 1 Oct 30 22:09:09 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 30 22:09:11 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:11 volumio volumio[962]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::ClearQueue Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::stop Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::clearPlayQueue Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::saveQueue Oct 30 22:09:11 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::addQueueItems Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::addQueueItems Oct 30 22:09:11 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:11 volumio volumio[962]: info: Adding Item to queue: http://kdhx-ice.streamguys1.com:80/live Oct 30 22:09:11 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 30 22:09:11 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::saveQueue Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::getTrackBlock Oct 30 22:09:11 volumio volumio[962]: info: CoreCommandRouter::volumioPlay Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::play index 0 Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::stop Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::play index undefined Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:11 volumio volumio[962]: info: CoreStateMachine::startPlaybackTimer Oct 30 22:09:11 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:11 volumio volumio[962]: info: [1730322551556] ControllerWebradio::clearAddPlayTrack Oct 30 22:09:11 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 22:09:11 volumio volumio[962]: info: sendMpdCommand stop took 4 milliseconds Oct 30 22:09:11 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 22:09:11 volumio volumio[962]: info: Oct 30 22:09:11 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:11 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:11 volumio volumio[962]: info: sendMpdCommand clear took 2 milliseconds Oct 30 22:09:11 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand load "http://kdhx-ice.streamguys1.com:80/live" Oct 30 22:09:11 volumio volumio[962]: info: Oct 30 22:09:11 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:11 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:11 volumio volumio[962]: info: Oct 30 22:09:11 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:11 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:11 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:11 volumio volumio[962]: info: ------------------------------ 12ms Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" Oct 30 22:09:12 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:12 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 1058ms Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 1056ms Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" took 4 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand play Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 13ms Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand play took 11 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 10ms Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 8ms Oct 30 22:09:12 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::ClearQueue Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::stop Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::clearPlayQueue Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::saveQueue Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::addQueueItems Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::addQueueItems Oct 30 22:09:12 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:12 volumio volumio[962]: info: Adding Item to queue: http://kdhx-ice.streamguys1.com:80/live Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: webradio , explodeUri Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushQueue Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::saveQueue Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::updateTrackBlock Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrackBlock Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioPlay Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::play index 0 Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::stop Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::play index undefined Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::startPlaybackTimer Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: [1730322552743] ControllerWebradio::clearAddPlayTrack Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand stop Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand stop took 5 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand clear Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:12 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:12 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand status took 7 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand clear took 7 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand status took 5 milliseconds Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand load "http://kdhx-ice.streamguys1.com:80/live" Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:12 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 30 22:09:12 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:12 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:12 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:12 volumio volumio[962]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 46ms Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 45ms Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: info: Oct 30 22:09:12 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:12 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:12 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 49ms Oct 30 22:09:12 volumio volumio[962]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 30 22:09:12 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:12 volumio volumio[962]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 30 22:09:12 volumio volumio[962]: info: ------------------------------ 60ms Oct 30 22:09:12 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:12 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" Oct 30 22:09:13 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:13 volumio volumio[962]: error: updateQueue error: null Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 296ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 295ms Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand add "http://kdhx-ice.streamguys1.com:80/live" took 4 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand play Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 9ms Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand play took 7 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 5ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 4ms Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 5 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 10 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 6 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 3 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus stop Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 49ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 62ms Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 47 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 46 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 45 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 4 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 167ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 161ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 159ms Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 124 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"live","artist":null,"album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 170ms Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 48ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 6ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 5ms Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces system playlist update Oct 30 22:09:13 volumio volumio[962]: info: Ignoring MPD Status Update Oct 30 22:09:13 volumio volumio[962]: info: Oct 30 22:09:13 volumio volumio[962]: ---------------------------- MPD announces state update: player Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::getState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand status Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 19ms Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 17 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 16ms Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 15 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 14ms Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand status took 13 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseState Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 5 milliseconds Oct 30 22:09:13 volumio volumio[962]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: verbose: ControllerMpd::parseTrackInfo Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ControllerMpd::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::servicePushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: verbose: STATE SERVICE {"status":"play","position":0,"seek":979,"duration":0,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"live","artist":"KDHX Live Webstream","album":null,"uri":"http://kdhx-ice.streamguys1.com:80/live","trackType":""} Oct 30 22:09:13 volumio volumio[962]: verbose: CURRENT POSITION 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState stateService play Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::syncState currentStatus play Oct 30 22:09:13 volumio volumio[962]: info: Received an update from plugin. extracting info from payload Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CorePlayQueue::getTrack 0 Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: CoreStateMachine::pushState Oct 30 22:09:13 volumio volumio[962]: info: CoreCommandRouter::volumioPushState Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 129ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 126ms Oct 30 22:09:13 volumio volumio[962]: info: ------------------------------ 124ms Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:13 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:30 volumio go-librespot[1134]: time="2024-10-30T22:09:30+01:00" level=error msg="failed receiving dealer message" error="failed to get reader: received close frame: status = StatusGoingAway and reason = \"\"" Oct 30 22:09:30 volumio go-librespot[1134]: panic: send on closed channel Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 74 gp=0x2f17e68 m=4 mp=0x2c47088 [running]: Oct 30 22:09:30 volumio go-librespot[1134]: panic({0x5eac10, 0x721a38}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:779 +0x12c fp=0x3105748 sp=0x31056f4 pc=0x58364 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x30c23c0, 0x31057a8, 0x1, 0x5793b0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:283 +0x5e4 fp=0x3105780 sp=0x3105748 pc=0x1f1c0 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x30c23c0, 0x31057a8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x3105798 sp=0x3105780 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withAppPlayer.func1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:252 +0x88 fp=0x31057ec sp=0x3105798 pc=0x5793b0 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x31057ec sp=0x31057ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by main.(*App).withAppPlayer in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3d4 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1 gp=0x2c02128 m=nil [runnable]: Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Client).do(0xaa8cc8, 0x311a5a8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/client.go:595 +0xff8 fp=0x2c55b54 sp=0x2c55b54 pc=0x3ea984 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Client).Do(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/client.go:590 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/session.retrieveClientToken({0x2cb41b0, 0x28}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/session/client_token.go:41 +0x674 fp=0x2c55c00 sp=0x2c55b54 pc=0x4c70c8 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/session.NewSessionFromOptions(0x2c55d10) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/session/session.go:51 +0x194 fp=0x2c55cac sp=0x2c55c00 pc=0x4c784c Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).newAppPlayer(0x2c9a240, {0x5f3118, 0x2d3e2b8}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:83 +0x2d8 fp=0x2c55d38 sp=0x2c55cac pc=0x577a9c Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withAppPlayer.func3({{0x2e62918, 0x6}, {0x2e481c0, 0xd8, 0xd8}, {0x31880a0, 0xb}, 0x2ef8400}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:308 +0x140 fp=0x2c55dac sp=0x2c55d38 pc=0x578cdc Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/zeroconf.(*Zeroconf).Serve(0x2ed7360, 0x2c55e88) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:272 +0x190 fp=0x2c55e50 sp=0x2c55dac pc=0x528be8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withAppPlayer(0x2c9a240, 0x2d813e0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x4b0 fp=0x2c55ec0 sp=0x2c55e50 pc=0x578aac Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withCredentials(0x2c9a240, {0x5f3170, 0x2c90180}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x3d4 fp=0x2c55f1c sp=0x2c55ec0 pc=0x578184 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).SpotifyToken(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131 Oct 30 22:09:30 volumio go-librespot[1134]: main.main() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x530 fp=0x2c55fa8 sp=0x2c55f1c pc=0x57a0cc Oct 30 22:09:30 volumio go-librespot[1134]: runtime.main() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x2fc fp=0x2c55fec sp=0x2c55fa8 pc=0x5c29c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c55fec sp=0x2c55fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 18 gp=0x2c84248 m=nil [force gc (idle), 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f938, 0xaa84c0, 0x11, 0xa, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3e7d4 sp=0x2c3e7c0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goparkunlock(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.forcegchelper() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xe4 fp=0x2c3e7ec sp=0x2c3e7d4 pc=0x5c668 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3e7ec sp=0x2c3e7ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.init.5 in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1c Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 19 gp=0x2c84368 m=nil [GC sweep wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f938, 0xaa8a10, 0xc, 0x9, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3efc4 sp=0x2c3efb0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goparkunlock(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.bgsweep(0x2c92000) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0x11c fp=0x2c3efe4 sp=0x2c3efc4 pc=0x4506c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcenable.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x28 fp=0x2c3efec sp=0x2c3efe4 pc=0x3518c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3efec sp=0x2c3efec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcenable in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x74 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 20 gp=0x2c84488 m=nil [GC scavenge wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f938, 0xaa9020, 0xd, 0xa, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3f7b4 sp=0x2c3f7a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goparkunlock(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.(*scavengerState).park(0xaa9020) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2c3f7c8 sp=0x2c3f7b4 pc=0x4238c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.bgscavenge(0x2c92000) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2c3f7e4 sp=0x2c3f7c8 pc=0x42ac4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcenable.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x28 fp=0x2c3f7ec sp=0x2c3f7e4 pc=0x35138 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3f7ec sp=0x2c3f7ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcenable in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xbc Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 21 gp=0x2c847e8 m=nil [finalizer wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7e4, 0xad3bac, 0x10, 0xa, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c3ff8c sp=0x2c3ff78 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.runfinq() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x110 fp=0x2c3ffec sp=0x2c3ff8c pc=0x33f78 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c3ffec sp=0x2c3ffec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.createfing in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x5c Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 22 gp=0x2c84908 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041f08, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c425dc sp=0x2c425c8 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041ef8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c425f4 sp=0x2c425dc pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041ef8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c42608 sp=0x2c425f4 pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ca8d38, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c4261c sp=0x2c42608 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Accept(0x2ca8d20) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2c42664 sp=0x2c4261c pc=0x10c974 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).accept(0x2ca8d20) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x2c426cc sp=0x2c42664 pc=0x2a2504 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPListener).accept(0x2cbc1b0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x2c426e4 sp=0x2c426cc pc=0x2ba604 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPListener).Accept(0x2cbc1b0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x2c42700 sp=0x2c426e4 pc=0x2b9670 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*onceCloseListener).Accept(0x2c7c560) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x34 fp=0x2c42718 sp=0x2c42700 pc=0x45e914 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve(0x2d26508, {0x72527c, 0x2cbc1b0}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x2c427b0 sp=0x2c42718 pc=0x43cfd0 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.Serve(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve(0x2c98780) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x5ec fp=0x2c427e4 sp=0x2c427b0 pc=0x57084c Oct 30 22:09:30 volumio go-librespot[1134]: main.NewApiServer.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x28 fp=0x2c427ec sp=0x2c427e4 pc=0x56ff20 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c427ec sp=0x2c427ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by main.NewApiServer in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x224 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 29 gp=0x2dd05a8 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041d28, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c59e84 sp=0x2c59e70 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041d18, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c59e9c sp=0x2c59e84 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041d18, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c59eb0 sp=0x2c59e9c pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ed7418, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c59ec4 sp=0x2c59eb0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).RawRead(0x2ed7400, 0x2cfc300) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2c59ef0 sp=0x2c59ec4 pc=0x10d578 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*rawConn).Read(0x2c2f0c8, 0x2cfc300) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2c59f10 sp=0x2c59ef0 pc=0x2b4a2c Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2c91250, 0x2cfc2d0, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2c59f40 sp=0x2c59f10 pc=0x4c9804 Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2ee57d8, {0x2fdc000, 0x10000, 0x10000}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x44c fp=0x2c59f9c sp=0x2c59f40 pc=0x4cd7ec Oct 30 22:09:30 volumio go-librespot[1134]: github.com/grandcat/zeroconf.(*Server).recv4(0x2c99600, 0x2ee57d0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2c59fe0 sp=0x2c59f9c pc=0x52407c Oct 30 22:09:30 volumio go-librespot[1134]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2c59fec sp=0x2c59fe0 pc=0x523d34 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c59fec sp=0x2c59fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 2 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 23 gp=0x2c84b48 m=nil [GC worker (idle), 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7f4, 0x2df5578, 0x1a, 0xa, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c40790 sp=0x2c4077c pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcBgMarkWorker() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c407ec sp=0x2c40790 pc=0x37db4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c407ec sp=0x2c407ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 39 gp=0x2dd07e8 m=nil [GC worker (idle), 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7f4, 0x2df5590, 0x1a, 0xa, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e28f90 sp=0x2e28f7c pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcBgMarkWorker() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2e28fec sp=0x2e28f90 pc=0x37db4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e28fec sp=0x2e28fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 24 gp=0x2c84c68 m=nil [GC worker (idle)]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7f4, 0x2cbaa98, 0x1a, 0xa, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c40f90 sp=0x2c40f7c pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcBgMarkWorker() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c40fec sp=0x2c40f90 pc=0x37db4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c40fec sp=0x2c40fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 25 gp=0x2c84d88 m=nil [GC worker (idle), 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7f4, 0x2cbaab0, 0x1a, 0xa, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c41790 sp=0x2c4177c pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gcBgMarkWorker() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xec fp=0x2c417ec sp=0x2c41790 pc=0x37db4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c417ec sp=0x2c417ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by runtime.gcBgMarkStartWorkers in goroutine 50 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x20 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 30 gp=0x2dd0ea8 m=nil [IO wait, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041c88, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c43e5c sp=0x2c43e48 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041c78, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c43e74 sp=0x2c43e5c pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041c78, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c43e88 sp=0x2c43e74 pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ed7468, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c43e9c sp=0x2c43e88 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).RawRead(0x2ed7450, 0x2e7e600) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2c43ec8 sp=0x2c43e9c pc=0x10d578 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*rawConn).Read(0x2c2f0f0, 0x2e7e600) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x38 fp=0x2c43ee8 sp=0x2c43ec8 pc=0x2b4a2c Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2c91260, 0x2e7e5d0, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x154 fp=0x2c43f18 sp=0x2c43ee8 pc=0x4c9804 Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247 Oct 30 22:09:30 volumio go-librespot[1134]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2ee5808, {0x2fc4000, 0x10000, 0x10000}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x2ec fp=0x2c43f9c sp=0x2c43f18 pc=0x4d1174 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/grandcat/zeroconf.(*Server).recv6(0x2c99600, 0x2ee5800) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2c43fe0 sp=0x2c43f9c pc=0x524270 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2c43fec sp=0x2c43fe0 pc=0x523cd8 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c43fec sp=0x2c43fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 2 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1174 gp=0x2c84a28 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e11bb8 sp=0x2e11ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2e11c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2e11bf0 sp=0x2e11bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2e11c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2e11c08 sp=0x2e11bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2cbc360}, 0x2f024c0}, {0x7253b0, 0x2caf228}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2e11c88 sp=0x2e11c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x2e11cd0 sp=0x2e11c88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e11ce0 sp=0x2e11cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2e11d1c sp=0x2e11ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2e11d40 sp=0x2e11d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e11d50 sp=0x2e11d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2caf228}, 0x2eea008) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2e11d6c sp=0x2e11d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2d941e0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2e11fdc sp=0x2e11d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2e11fec sp=0x2e11fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e11fec sp=0x2e11fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 53 gp=0x2f179e8 m=nil [runnable]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f938, 0xaae418, 0x15, 0x5, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30aed84 sp=0x30aed70 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goparkunlock(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.semacquire1(0xa673b0, 0x0, 0x3, 0x1, 0x15) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sema.go:160 +0x270 fp=0x30aedac sp=0x30aed84 pc=0x71220 Oct 30 22:09:30 volumio go-librespot[1134]: sync.runtime_SemacquireMutex(0xa673b0, 0x0, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/sema.go:77 +0x3c fp=0x30aedc4 sp=0x30aedac pc=0x90784 Oct 30 22:09:30 volumio go-librespot[1134]: sync.(*Mutex).lockSlow(0xa673ac) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/sync/mutex.go:171 +0x280 fp=0x30aedf8 sp=0x30aedc4 pc=0x9ecfc Oct 30 22:09:30 volumio go-librespot[1134]: sync.(*Mutex).Lock(0xa673ac) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/sync/mutex.go:90 +0x4c fp=0x30aee0c sp=0x30aedf8 pc=0x9e9fc Oct 30 22:09:30 volumio go-librespot[1134]: github.com/sirupsen/logrus.(*MutexWrap).Lock(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/sirupsen/logrus@v1.9.3/logger.go:61 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/sirupsen/logrus.(*Entry).log(0x2ec2c40, 0x2, {0x2f00e70, 0x17}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:233 +0x340 fp=0x30aeebc sp=0x30aee0c pc=0x1607cc Oct 30 22:09:30 volumio go-librespot[1134]: github.com/sirupsen/logrus.(*Entry).Log(0x2ec2c40, 0x2, {0x30aeef4, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:304 +0x78 fp=0x30aeed4 sp=0x30aeebc pc=0x160f64 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/sirupsen/logrus.(*Entry).Logf(0x2ec2c40, 0x2, {0x66f1e4, 0x17}, {0x0, 0x0, 0x0}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:349 +0xc0 fp=0x30aeefc sp=0x30aeed4 pc=0x161300 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/sirupsen/logrus.(*Entry).Errorf(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/sirupsen/logrus@v1.9.3/entry.go:378 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/ap.(*Accesspoint).recvLoop(0x2d27868) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:253 +0x27c fp=0x30aefe4 sp=0x30aeefc pc=0x48cfc8 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x28 fp=0x30aefec sp=0x30aefe4 pc=0x48cd20 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30aefec sp=0x30aefec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 73 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:240 +0x60 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 73 gp=0x2f17d48 m=nil [select]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c331dc sp=0x2c331c8 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.selectgo(0x2c332d4, 0x2c332a4, 0x0, 0x0, 0x2, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x2c3327c sp=0x2c331dc pc=0x705e4 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x2c33348, {0x724ee8, 0x31892a0}, 0x0, {0x0, 0x0}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:112 +0x270 fp=0x2c332fc sp=0x2c3327c pc=0x496414 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/cenkalti/backoff/v4.RetryNotifyWithData[...](...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:54 Oct 30 22:09:30 volumio go-librespot[1134]: github.com/cenkalti/backoff/v4.RetryWithData[...](0x2c33348, {0x724ee8, 0x31892a0}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:43 +0x54 fp=0x2c33328 sp=0x2c332fc pc=0x49617c Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/audio.(*HttpChunkedReader).downloadChunk(0x2c00c60, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:110 +0x84 fp=0x2c33354 sp=0x2c33328 pc=0x4921f8 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/audio.NewHttpChunkedReader(0x30ab740, {0x302e300, 0xb9}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/audio/chunked_reader.go:74 +0x194 fp=0x2c333c8 sp=0x2c33354 pc=0x491b34 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/player.(*Player).NewStream(0x30aab00, {{0x2e7d178, 0x5}, {0x31891d0, 0x10, 0x10}}, 0x140, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/player/player.go:395 +0x724 fp=0x2c335a0 sp=0x2c333c8 pc=0x4a8624 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*AppPlayer).loadCurrentTrack(0x2ed74a0, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:222 +0x470 fp=0x2c337a4 sp=0x2c335a0 pc=0x573f7c Oct 30 22:09:30 volumio go-librespot[1134]: main.(*AppPlayer).loadContext(0x2ed74a0, 0x30aae00, 0x0, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/controls.go:180 +0x6a8 fp=0x2c33838 sp=0x2c337a4 pc=0x573a14 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*AppPlayer).handleApiRequest(0x2ed74a0, {{0x665343, 0x4}, {0x600550, 0x30d6588}, 0x310a480}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:383 +0x4e8 fp=0x2c338fc sp=0x2c33838 pc=0x57d274 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*AppPlayer).Run(0x2ed74a0, 0x30c23c0) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:450 +0x530 fp=0x2c33fe0 sp=0x2c338fc pc=0x57e284 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withAppPlayer.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x30 fp=0x2c33fec sp=0x2c33fe0 pc=0x57944c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c33fec sp=0x2c33fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by main.(*App).withAppPlayer in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x348 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 75 gp=0x30ca008 m=nil [chan receive, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c920f0, 0xe, 0x7, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x3105f3c sp=0x3105f28 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv(0x2c920c0, 0x3105fe0, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x3105f78 sp=0x3105f3c pc=0x1fdc4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv2(0x2c920c0, 0x3105fe0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x20 fp=0x3105f8c sp=0x3105f78 pc=0x1f908 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*App).withAppPlayer.func2() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x5c fp=0x3105fec sp=0x3105f8c pc=0x578f90 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x3105fec sp=0x3105fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by main.(*App).withAppPlayer in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x45c Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 76 gp=0x30ca128 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041dc8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x31065e8 sp=0x31065d4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041db8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x3106600 sp=0x31065e8 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041db8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x3106614 sp=0x3106600 pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ed73c8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3106628 sp=0x3106614 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Accept(0x2ed73b0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x294 fp=0x3106670 sp=0x3106628 pc=0x10c974 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).accept(0x2ed73b0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x20 fp=0x31066d8 sp=0x3106670 pc=0x2a2504 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPListener).accept(0x2f044e0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x20 fp=0x31066f0 sp=0x31066d8 pc=0x2ba604 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPListener).Accept(0x2f044e0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0x310670c sp=0x31066f0 pc=0x2b9670 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*onceCloseListener).Accept(0x30ac860) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x34 fp=0x3106724 sp=0x310670c pc=0x45e914 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve(0x2caf0e8, {0x72527c, 0x2f044e0}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x314 fp=0x31067bc sp=0x3106724 pc=0x43cfd0 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.Serve(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/zeroconf.(*Zeroconf).Serve.func2() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x8c fp=0x31067ec sp=0x31067bc pc=0x528d7c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x31067ec sp=0x31067ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 969 gp=0x30ca248 m=nil [IO wait, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041968, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e276b4 sp=0x2e276a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041958, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e276cc sp=0x2e276b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041958, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e276e0 sp=0x2e276cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2cedd28, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e276f4 sp=0x2e276e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2cedd10, {0x31869ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e2773c sp=0x2e276f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2cedd10, {0x31869ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e27768 sp=0x2e2773c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2f63100, {0x31869ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e27794 sp=0x2e27768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2f63100, {0x31869ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e277b4 sp=0x2e27794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x31869a0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e277e4 sp=0x2e277b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e277ec sp=0x2e277e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e277ec sp=0x2e277ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 979 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1196 gp=0x30ca6c8 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041e68, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e2beb4 sp=0x2e2bea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041e58, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e2becc sp=0x2e2beb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041e58, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e2bee0 sp=0x2e2becc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ff02e8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e2bef4 sp=0x2e2bee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2ff02d0, {0x30ac10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e2bf3c sp=0x2e2bef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2ff02d0, {0x30ac10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e2bf68 sp=0x2e2bf3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309c018, {0x30ac10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e2bf94 sp=0x2e2bf68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309c018, {0x30ac10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e2bfb4 sp=0x2e2bf94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x30ac100) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e2bfe4 sp=0x2e2bfb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e2bfec sp=0x2e2bfe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e2bfec sp=0x2e2bfec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1174 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 80 gp=0x2f17c28 m=nil [IO wait, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x760418c8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30798a4 sp=0x3079890 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x760418b8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x30798bc sp=0x30798a4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x760418b8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x30798d0 sp=0x30798bc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2dba0b8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x30798e4 sp=0x30798d0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2dba0a0, {0x2d7c000, 0x1000, 0x1000}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x307992c sp=0x30798e4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2dba0a0, {0x2d7c000, 0x1000, 0x1000}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x3079958 sp=0x307992c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309c0c0, {0x2d7c000, 0x1000, 0x1000}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x3079984 sp=0x3079958 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309c0c0, {0x2d7c000, 0x1000, 0x1000}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x30799a4 sp=0x3079984 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: io.(*multiReader).Read(0x2d8c400, {0x2d7c000, 0x1000, 0x1000}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0xb4 fp=0x30799d0 sp=0x30799a4 pc=0x103318 Oct 30 22:09:30 volumio go-librespot[1134]: bufio.(*Reader).fill(0x2f70240) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x10c fp=0x30799f4 sp=0x30799d0 pc=0x15a4b8 Oct 30 22:09:30 volumio go-librespot[1134]: bufio.(*Reader).ReadByte(0x2f70240) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x28 fp=0x3079a00 sp=0x30799f4 pc=0x15ad3c Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.readFrameHeader(0x2f70240, {0x2ea62b8, 0x8, 0x8}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x3079a40 sp=0x3079a00 pc=0x4771e0 Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2ea6288, {0x725688, 0xad3b20}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x3079b00 sp=0x3079a40 pc=0x479e44 Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).readLoop(0x2ea6288, {0x725688, 0xad3b20}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x3079bc4 sp=0x3079b00 pc=0x47992c Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).reader(0x2ea6288, {0x725688, 0xad3b20}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x3079c50 sp=0x3079bc4 pc=0x47ab40 Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).Reader(...) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).Read(0x2ea6288, {0x725688, 0xad3b20}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x3079c78 sp=0x3079c50 pc=0x479210 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func14({0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x22c fp=0x3079cd0 sp=0x3079c78 pc=0x570c88 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e180, {0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x3079ce0 sp=0x3079cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x3079d1c sp=0x3079ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x3079d40 sp=0x3079d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x3079d50 sp=0x3079d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2caefa8}, 0x2caef08) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x3079d6c sp=0x3079d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2cda2a0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x3079fdc sp=0x3079d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x3079fec sp=0x3079fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x3079fec sp=0x3079fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 98 gp=0x30da008 m=nil [select, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f96c, 0x0, 0x9, 0x3, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x31096a8 sp=0x3109694 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.selectgo(0x31097a4, 0x310976c, 0x0, 0x0, 0x5, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0xaf0 fp=0x3109748 sp=0x31096a8 pc=0x705e4 Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2ea6288) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x31097e4 sp=0x3109748 pc=0x47408c Oct 30 22:09:30 volumio go-librespot[1134]: nhooyr.io/websocket.newConn.gowrap1() Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x31097ec sp=0x31097e4 pc=0x473c80 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x31097ec sp=0x31097ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by nhooyr.io/websocket.newConn in goroutine 80 Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 101 gp=0x30da368 m=nil [chan receive, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x310a4b0, 0xe, 0x7, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30b2bb8 sp=0x30b2ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv(0x310a480, 0x30bdc78, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x30b2bf4 sp=0x30b2bb8 pc=0x1fdc4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv1(0x310a480, 0x30bdc78) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:442 +0x20 fp=0x30b2c08 sp=0x30b2bf4 pc=0x1f8e4 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x30d6588}, 0x310a480}, {0x7253b0, 0x2caf688}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:253 +0x70 fp=0x30b2c88 sp=0x30b2c08 pc=0x56ffbc Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x30b2cd0 sp=0x30b2c88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x30b2ce0 sp=0x30b2cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x30b2d1c sp=0x30b2ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x30b2d40 sp=0x30b2d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x30b2d50 sp=0x30b2d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2caf688}, 0x2caf5e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x30b2d6c sp=0x30b2d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2cda4e0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x30b2fdc sp=0x30b2d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x30b2fec sp=0x30b2fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30b2fec sp=0x30b2fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 102 gp=0x30da488 m=nil [IO wait, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041828, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e266b4 sp=0x2e266a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041818, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e266cc sp=0x2e266b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041818, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e266e0 sp=0x2e266cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2dba4c8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e266f4 sp=0x2e266e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2dba4b0, {0x2c7cf8d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e2673c sp=0x2e266f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2dba4b0, {0x2c7cf8d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e26768 sp=0x2e2673c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309c820, {0x2c7cf8d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e26794 sp=0x2e26768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309c820, {0x2c7cf8d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e267b4 sp=0x2e26794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2c7cf80) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e267e4 sp=0x2e267b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e267ec sp=0x2e267e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e267ec sp=0x2e267ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 101 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 271 gp=0x30daea8 m=nil [chan receive, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x30ec2b0, 0xe, 0x7, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x307dbb8 sp=0x307dba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv(0x30ec280, 0x307dc78, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x307dbf4 sp=0x307dbb8 pc=0x1fdc4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv1(0x30ec280, 0x307dc78) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:442 +0x20 fp=0x307dc08 sp=0x307dbf4 pc=0x1f8e4 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x30d76e0}, 0x30ec280}, {0x7253b0, 0x2d26f08}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:253 +0x70 fp=0x307dc88 sp=0x307dc08 pc=0x56ffbc Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x307dcd0 sp=0x307dc88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x307dce0 sp=0x307dcd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x307dd1c sp=0x307dce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x307dd40 sp=0x307dd1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x307dd50 sp=0x307dd40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2d26f08}, 0x2d26e68) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x307dd6c sp=0x307dd50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2d5bc20, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x307dfdc sp=0x307dd6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x307dfec sp=0x307dfdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x307dfec sp=0x307dfec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 254 gp=0x30db0e8 m=nil [IO wait, 5 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041788, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2f80eb4 sp=0x2f80ea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041778, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2f80ecc sp=0x2f80eb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041778, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2f80ee0 sp=0x2f80ecc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2e43eb8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2f80ef4 sp=0x2f80ee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2e43ea0, {0x2e9aa6d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2f80f3c sp=0x2f80ef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2e43ea0, {0x2e9aa6d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2f80f68 sp=0x2f80f3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2f633c8, {0x2e9aa6d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2f80f94 sp=0x2f80f68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2f633c8, {0x2e9aa6d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2f80fb4 sp=0x2f80f94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2e9aa60) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2f80fe4 sp=0x2f80fb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2f80fec sp=0x2f80fe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2f80fec sp=0x2f80fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 271 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1034 gp=0x30db448 m=nil [IO wait, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041be8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e29eb4 sp=0x2e29ea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041bd8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e29ecc sp=0x2e29eb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041bd8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e29ee0 sp=0x2e29ecc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ed4a68, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e29ef4 sp=0x2e29ee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2ed4a50, {0x318724d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e29f3c sp=0x2e29ef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2ed4a50, {0x318724d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e29f68 sp=0x2e29f3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2c94060, {0x318724d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e29f94 sp=0x2e29f68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2c94060, {0x318724d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e29fb4 sp=0x2e29f94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x3187240) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e29fe4 sp=0x2e29fb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e29fec sp=0x2e29fe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e29fec sp=0x2e29fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1033 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1049 gp=0x30caa28 m=nil [IO wait, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041aa8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c41eb4 sp=0x2c41ea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041a98, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c41ecc sp=0x2c41eb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041a98, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c41ee0 sp=0x2c41ecc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ed4928, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c41ef4 sp=0x2c41ee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2ed4910, {0x2f0ec0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c41f3c sp=0x2c41ef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2ed4910, {0x2f0ec0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2c41f68 sp=0x2c41f3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2c94000, {0x2f0ec0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2c41f94 sp=0x2c41f68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2c94000, {0x2f0ec0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2c41fb4 sp=0x2c41f94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2f0ec00) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2c41fe4 sp=0x2c41fb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2c41fec sp=0x2c41fe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c41fec sp=0x2c41fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1031 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1033 gp=0x3056008 m=nil [chan send, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x3078bb8 sp=0x3078ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x3078c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x3078bf0 sp=0x3078bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x3078c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x3078c08 sp=0x3078bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2cbc240}, 0x2eda840}, {0x7253b0, 0x2ede5a8}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x3078c88 sp=0x3078c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x3078cd0 sp=0x3078c88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x3078ce0 sp=0x3078cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x3078d1c sp=0x3078ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x3078d40 sp=0x3078d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x3078d50 sp=0x3078d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2ede5a8}, 0x2ede508) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x3078d6c sp=0x3078d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2ef40c0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x3078fdc sp=0x3078d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x3078fec sp=0x3078fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x3078fec sp=0x3078fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1210 gp=0x30cac68 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x760413c8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e256b4 sp=0x2e256a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x760413b8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e256cc sp=0x2e256b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x760413b8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e256e0 sp=0x2e256cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2f10568, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e256f4 sp=0x2e256e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2f10550, {0x2e9a10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e2573c sp=0x2e256f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2f10550, {0x2e9a10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e25768 sp=0x2e2573c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2f635a0, {0x2e9a10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e25794 sp=0x2e25768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2f635a0, {0x2e9a10d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e257b4 sp=0x2e25794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2e9a100) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e257e4 sp=0x2e257b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e257ec sp=0x2e257e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e257ec sp=0x2e257ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1693 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1031 gp=0x30caea8 m=nil [chan send, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c53bb8 sp=0x2c53ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2c53c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2c53bf0 sp=0x2c53bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2e0fc90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2c53c08 sp=0x2c53bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665337, 0x4}, {0x5cca88, 0x2c11458}, 0x2d464c0}, {0x7253b0, 0x2cf7868}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2c53c88 sp=0x2c53c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func8({0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:351 +0x1b4 fp=0x2c53cd0 sp=0x2c53c88 pc=0x571a38 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e0f0, {0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c53ce0 sp=0x2c53cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2c53d1c sp=0x2c53ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2c53d40 sp=0x2c53d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c53d50 sp=0x2c53d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2cf7868}, 0x2cf77c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2c53d6c sp=0x2c53d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2ef4060, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2c53fdc sp=0x2c53d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2c53fec sp=0x2c53fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c53fec sp=0x2c53fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 979 gp=0x3056248 m=nil [chan send, 3 minutes]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x307ebb8 sp=0x307eba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x307ec90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x307ebf0 sp=0x307ebb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x307ec90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x307ec08 sp=0x307ebf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2e65560}, 0x2ec3580}, {0x7253b0, 0x2eb3908}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x307ec88 sp=0x307ec08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x307ecd0 sp=0x307ec88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x307ece0 sp=0x307ecd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x307ed1c sp=0x307ece0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x307ed40 sp=0x307ed1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x307ed50 sp=0x307ed40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2eb3908}, 0x2eb3868) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x307ed6c sp=0x307ed50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2d284e0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x307efdc sp=0x307ed6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x307efec sp=0x307efdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x307efec sp=0x307efec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1498 gp=0x3056488 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041648, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e286b4 sp=0x2e286a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041638, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2e286cc sp=0x2e286b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041638, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2e286e0 sp=0x2e286cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2dba978, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2e286f4 sp=0x2e286e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2dba960, {0x2d81c0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2e2873c sp=0x2e286f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2dba960, {0x2d81c0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2e28768 sp=0x2e2873c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309d750, {0x2d81c0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2e28794 sp=0x2e28768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309d750, {0x2d81c0d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2e287b4 sp=0x2e28794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2d81c00) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2e287e4 sp=0x2e287b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2e287ec sp=0x2e287e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e287ec sp=0x2e287ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1497 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1523 gp=0x3056908 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041508, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x3102eb4 sp=0x3102ea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x760414f8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x3102ecc sp=0x3102eb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x760414f8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x3102ee0 sp=0x3102ecc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2e436e8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3102ef4 sp=0x3102ee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2e436d0, {0x2c7c7ed, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x3102f3c sp=0x3102ef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2e436d0, {0x2c7c7ed, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x3102f68 sp=0x3102f3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2f63148, {0x2c7c7ed, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x3102f94 sp=0x3102f68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2f63148, {0x2c7c7ed, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x3102fb4 sp=0x3102f94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2c7c7e0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x3102fe4 sp=0x3102fb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x3102fec sp=0x3102fe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x3102fec sp=0x3102fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1512 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1501 gp=0x3056a28 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x760415a8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x31086b4 sp=0x31086a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041598, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x31086cc sp=0x31086b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041598, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x31086e0 sp=0x31086cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2dbab58, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x31086f4 sp=0x31086e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2dbab40, {0x2d81ced, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x310873c sp=0x31086f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2dbab40, {0x2d81ced, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x3108768 sp=0x310873c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309d798, {0x2d81ced, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x3108794 sp=0x3108768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309d798, {0x2d81ced, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x31087b4 sp=0x3108794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2d81ce0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x31087e4 sp=0x31087b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x31087ec sp=0x31087e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x31087ec sp=0x31087ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1500 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1512 gp=0x3056c68 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e0ebb8 sp=0x2e0eba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2e0ec90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2e0ebf0 sp=0x2e0ebb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2e0ec90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2e0ec08 sp=0x2e0ebf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2f547b0}, 0x310be80}, {0x7253b0, 0x2eb28c8}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2e0ec88 sp=0x2e0ec08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x2e0ecd0 sp=0x2e0ec88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e0ece0 sp=0x2e0ecd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2e0ed1c sp=0x2e0ece0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2e0ed40 sp=0x2e0ed1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e0ed50 sp=0x2e0ed40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2eb28c8}, 0x2eb2648) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2e0ed6c sp=0x2e0ed50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2ef4b40, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2e0efdc sp=0x2e0ed6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2e0efec sp=0x2e0efdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e0efec sp=0x2e0efec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1348 gp=0x30cb328 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c57bb8 sp=0x2c57ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2c57c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2c57bf0 sp=0x2c57bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2c57c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2c57c08 sp=0x2c57bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2d910f8}, 0x310a700}, {0x7253b0, 0x2eeb368}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2c57c88 sp=0x2c57c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x2c57cd0 sp=0x2c57c88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c57ce0 sp=0x2c57cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2c57d1c sp=0x2c57ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2c57d40 sp=0x2c57d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c57d50 sp=0x2c57d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2eeb368}, 0x2eeb2c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2c57d6c sp=0x2c57d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2d95740, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2c57fdc sp=0x2c57d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2c57fec sp=0x2c57fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c57fec sp=0x2c57fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1349 gp=0x30cb448 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x760416e8, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2f7b6b4 sp=0x2f7b6a0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x760416d8, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2f7b6cc sp=0x2f7b6b4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x760416d8, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2f7b6e0 sp=0x2f7b6cc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2f38568, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2f7b6f4 sp=0x2f7b6e0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2f38550, {0x30ad6ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2f7b73c sp=0x2f7b6f4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2f38550, {0x30ad6ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2f7b768 sp=0x2f7b73c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x309cf38, {0x30ad6ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2f7b794 sp=0x2f7b768 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x309cf38, {0x30ad6ad, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2f7b7b4 sp=0x2f7b794 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x30ad6a0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2f7b7e4 sp=0x2f7b7b4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2f7b7ec sp=0x2f7b7e4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2f7b7ec sp=0x2f7b7ec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1348 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1500 gp=0x30cb568 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c52bb8 sp=0x2c52ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2c52c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2c52bf0 sp=0x2c52bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2c52c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2c52c08 sp=0x2c52bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x667ab3, 0xa}, {0x5cca58, 0xa7d350}, 0x310bc40}, {0x7253b0, 0x2eb2468}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2c52c88 sp=0x2c52c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func9({0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:365 +0x1c0 fp=0x2c52cd0 sp=0x2c52c88 pc=0x57180c Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e108, {0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c52ce0 sp=0x2c52cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2c52d1c sp=0x2c52ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2c52d40 sp=0x2c52d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2c52d50 sp=0x2c52d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2eb2468}, 0x2eb2328) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2c52d6c sp=0x2c52d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x309f500, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2c52fdc sp=0x2c52d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2c52fec sp=0x2c52fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c52fec sp=0x2c52fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1497 gp=0x30cb7a8 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30b0bb8 sp=0x30b0ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x30b0c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x30b0bf0 sp=0x30b0bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x30b0c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x30b0c08 sp=0x30b0bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x667ab3, 0xa}, {0x5cca58, 0xa7d3b8}, 0x310bc00}, {0x7253b0, 0x2eb2288}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x30b0c88 sp=0x30b0c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func9({0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:365 +0x1c0 fp=0x30b0cd0 sp=0x30b0c88 pc=0x57180c Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e108, {0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x30b0ce0 sp=0x30b0cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x30b0d1c sp=0x30b0ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x30b0d40 sp=0x30b0d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x30b0d50 sp=0x30b0d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2eb2288}, 0x2eb21e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x30b0d6c sp=0x30b0d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x309f4a0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x30b0fdc sp=0x30b0d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x30b0fec sp=0x30b0fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30b0fec sp=0x30b0fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1484 gp=0x30cb8c8 m=nil [chan send]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2c92170, 0xf, 0x6, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2e51bb8 sp=0x2e51ba4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend(0x2c92140, 0x2e51c90, 0x1, 0x56ff90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:259 +0x378 fp=0x2e51bf0 sp=0x2e51bb8 pc=0x1ef54 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chansend1(0x2c92140, 0x2e51c90) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:145 +0x28 fp=0x2e51c08 sp=0x2e51bf0 pc=0x1ebd8 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).handleRequest(0x2c98780, {{0x665343, 0x4}, {0x600550, 0x2fd56c8}, 0x2ce6400}, {0x7253b0, 0x2d26788}) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:252 +0x44 fp=0x2e51c88 sp=0x2e51c08 pc=0x56ff90 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.func3({0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:303 +0x1b0 fp=0x2e51cd0 sp=0x2e51c88 pc=0x571efc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c2e070, {0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e51ce0 sp=0x2e51cd0 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a080, {0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x2e51d1c sp=0x2e51ce0 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0xa4 fp=0x2e51d40 sp=0x2e51d1c pc=0x570a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x2c0e1c0, {0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x2e51d50 sp=0x2e51d40 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2d26508}, {0x7253b0, 0x2d26788}, 0x2d266e8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x2e51d6c sp=0x2e51d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2d296e0, {0x7256a8, 0x2d3e138}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x2e51fdc sp=0x2e51d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x2e51fec sp=0x2e51fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2e51fec sp=0x2e51fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 22 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1485 gp=0x30cb9e8 m=nil [IO wait]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f928, 0x76041468, 0x2, 0x2, 0x5) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x2c44eb4 sp=0x2c44ea0 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.netpollblock(0x76041458, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0x100 fp=0x2c44ecc sp=0x2c44eb4 pc=0x540f0 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.runtime_pollWait(0x76041458, 0x72) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x54 fp=0x2c44ee0 sp=0x2c44ecc pc=0x8eba8 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).wait(0x2ced328, 0x72, 0x0) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2c44ef4 sp=0x2c44ee0 pc=0x108108 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*pollDesc).waitRead(...) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89 Oct 30 22:09:30 volumio go-librespot[1134]: internal/poll.(*FD).Read(0x2ced310, {0x2c97e2d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2c44f3c sp=0x2c44ef4 pc=0x1092f4 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*netFD).Read(0x2ced310, {0x2c97e2d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x38 fp=0x2c44f68 sp=0x2c44f3c pc=0x2a0610 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*conn).Read(0x2c958a8, {0x2c97e2d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x48 fp=0x2c44f94 sp=0x2c44f68 pc=0x2b07f0 Oct 30 22:09:30 volumio go-librespot[1134]: net.(*TCPConn).Read(0x2c958a8, {0x2c97e2d, 0x1, 0x1}) Oct 30 22:09:30 volumio go-librespot[1134]: :1 +0x44 fp=0x2c44fb4 sp=0x2c44f94 pc=0x2c3a44 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).backgroundRead(0x2c97e20) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:681 +0x40 fp=0x2c44fe4 sp=0x2c44fb4 pc=0x431abc Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*connReader).startBackgroundRead.gowrap2() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x28 fp=0x2c44fec sp=0x2c44fe4 pc=0x4319f4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x2c44fec sp=0x2c44fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*connReader).startBackgroundRead in goroutine 1484 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:677 +0x160 Oct 30 22:09:30 volumio go-librespot[1134]: goroutine 1693 gp=0x30cbb08 m=nil [chan receive]: Oct 30 22:09:30 volumio go-librespot[1134]: runtime.gopark(0x68f7c8, 0x2ef8430, 0xe, 0x7, 0x2) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0x104 fp=0x30b4ae8 sp=0x30b4ad4 pc=0x5c82c Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv(0x2ef8400, 0x30b4b5f, 0x1) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x4b0 fp=0x30b4b24 sp=0x30b4ae8 pc=0x1fdc4 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.chanrecv1(0x2ef8400, 0x30b4b5f) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:442 +0x20 fp=0x30b4b38 sp=0x30b4b24 pc=0x1f8e4 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/zeroconf.(*Zeroconf).handleAddUser(0x2ed7360, {0x7253b0, 0x311a468}, 0x311a3c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:204 +0x874 fp=0x30b4ca8 sp=0x30b4b38 pc=0x528474 Oct 30 22:09:30 volumio go-librespot[1134]: go-librespot/zeroconf.(*Zeroconf).Serve.func1({0x7253b0, 0x311a468}, 0x311a3c8) Oct 30 22:09:30 volumio go-librespot[1134]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:254 +0x220 fp=0x30b4d04 sp=0x30b4ca8 pc=0x528fe8 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.HandlerFunc.ServeHTTP(0x309cc50, {0x7253b0, 0x311a468}, 0x311a3c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x34 fp=0x30b4d14 sp=0x30b4d04 pc=0x4395b4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*ServeMux).ServeHTTP(0x2d1a900, {0x7253b0, 0x311a468}, 0x311a3c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x208 fp=0x30b4d50 sp=0x30b4d14 pc=0x43b414 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.serverHandler.ServeHTTP({0x2caf0e8}, {0x7253b0, 0x311a468}, 0x311a3c8) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0xe0 fp=0x30b4d6c sp=0x30b4d50 pc=0x43cba4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*conn).serve(0x2ef5020, {0x7256a8, 0x2f05e60}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x61c fp=0x30b4fdc sp=0x30b4d6c pc=0x437df4 Oct 30 22:09:30 volumio go-librespot[1134]: net/http.(*Server).Serve.gowrap3() Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x38 fp=0x30b4fec sp=0x30b4fdc pc=0x43d448 Oct 30 22:09:30 volumio go-librespot[1134]: runtime.goexit({}) Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_arm.s:859 +0x4 fp=0x30b4fec sp=0x30b4fec pc=0x9346c Oct 30 22:09:30 volumio go-librespot[1134]: created by net/http.(*Server).Serve in goroutine 76 Oct 30 22:09:30 volumio go-librespot[1134]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x468 Oct 30 22:09:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Oct 30 22:09:30 volumio go-librespot[1134]: Aborted Oct 30 22:09:30 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/volume: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/seek: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Oct 30 22:09:30 volumio volumio[962]: info: Connection to go-librespot Websocket closed Oct 30 22:09:33 volumio volumio[962]: info: Initializing connection to go-librespot Websocket Oct 30 22:09:33 volumio volumio[962]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Oct 30 22:09:34 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Oct 30 22:09:34 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Oct 30 22:09:34 volumio systemd[1]: Stopped go-librespot Daemon. Oct 30 22:09:34 volumio systemd[1]: Started go-librespot Daemon. Oct 30 22:09:34 volumio go-librespot[1551]: Librespot-go daemon starting... Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=info msg="generated new device id: 343590ad4a44bef0cc0a79b0459a5b425eada8a8" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="stored credentials found for rreo762m46db98sbpuy6yxwzx" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="zeroconf server listening on port 38409" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="obtained new client token: AACu2/BK/OOhnzwjRRrOOhPA5SY+wtwrTAcN2HPENvt+qzX/0Ma9I+G/RjKNXX29fZB/j3epf47DvsNd5zw9atWWUg4auFUCVED65Iwi/udjGHhgXJkwKH2PyAlsHQq/cjvupA6xqkBmzai9tg6EoosYctiDzeEWK9UXn/SQaBFzfxtl7If+OGqXv1IYP6vhR4FJczXs3J3L+X+Cxln+Uf16sqQxE/v9QRkAyjJyszrl1xDIEHKVDYicSZIB" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=info msg="connected to ap-gew1.spotify.com:4070" Oct 30 22:09:34 volumio go-librespot[1551]: time="2024-10-30T22:09:34+01:00" level=debug msg="completed keyexchange" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="completed challenge" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="authenticated as rreo762m46db98sbpuy6yxwzx" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="authenticated as rreo762m46db98sbpuy6yxwzx" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="dealer connection opened" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="initializing zeroconf session, username: rreo762m46db98sbpuy6yxwzx" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="autoplay enabled: false" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="received connection id: M2RmOWIwNDUtNDdkNS00YTQ2LWE1MmUtODIxZDAyOTI5OTc5K2RlYWxlcit0Y3A6Ly8wYWNhNDFmMy5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArQkE3MjIwNTc5M0REMjgyNzdCQUU1MTYzRThDNkM0MjgzNEFGOUFCMEE5NjhBNTcxODlGNURGRjg3N0U5MTE2MA==" Oct 30 22:09:35 volumio go-librespot[1551]: time="2024-10-30T22:09:35+01:00" level=debug msg="put connect state because NEW_DEVICE" Oct 30 22:09:36 volumio volumio[962]: info: Initializing connection to go-librespot Websocket Oct 30 22:09:36 volumio go-librespot[1551]: time="2024-10-30T22:09:36+01:00" level=debug msg="new websocket client" Oct 30 22:09:36 volumio volumio[962]: info: Connection to go-librespot Websocket established Oct 30 22:09:39 volumio volumio[962]: info: Getting Spotify volume Oct 30 22:09:39 volumio volumio[962]: info: Spotify volume: 100 Oct 30 22:09:40 volumio volumio[962]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Oct 30 22:09:40 volumio volumio[962]: info: CoreCommandRouter::volumioGetState Oct 30 22:09:40 volumio volumio[962]: SPOTIFY: RECEIVED VOLUMIO VOLUME 29 Oct 30 22:09:40 volumio volumio[962]: SPOTIFY: SPOTIFY VOLUME 100 Oct 30 22:09:40 volumio volumio[962]: SPOTIFY: VOLUMIO VOLUME 29 Oct 30 22:09:40 volumio volumio[962]: SPOTIFY: DELTA VOLUME ENOUGH: true Oct 30 22:09:40 volumio volumio[962]: info: Setting Spotify Volume from Volumio: 29 Oct 30 22:09:41 volumio volumio[962]: SPOTIFY: SETTING SPOTIFY VOLUME 29 Oct 30 22:09:41 volumio volumio[962]: info: Sending Spotify command with payload to local API: /player/volume Oct 30 22:09:41 volumio go-librespot[1551]: time="2024-10-30T22:09:41+01:00" level=debug msg="update volume to 19005/65535" Oct 30 22:09:41 volumio go-librespot[1551]: time="2024-10-30T22:09:41+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Oct 30 22:09:41 volumio go-librespot[1551]: time="2024-10-30T22:09:41+01:00" level=trace msg="emitting websocket event: volume" Oct 30 22:09:41 volumio volumio[962]: SPOTIFY: received: {"type":"volume","data":{"value":29,"max":100}} Oct 30 22:09:41 volumio volumio[962]: SPOTIFY: RECEIVED SPOTIFY VOLUME 29 Oct 30 22:09:57 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 22:09:57 volumio volumio[962]: info: Preload queue cleared Oct 30 22:09:59 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 22:09:59 volumio volumio[962]: info: Preload queue cleared Oct 30 22:10:03 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 22:10:03 volumio volumio[962]: info: Preload queue cleared Oct 30 22:10:04 volumio volumio[962]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 30 22:10:04 volumio volumio[962]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:10:04 volumio volumio[962]: TypeError: Cannot read property 'length' of undefined Oct 30 22:10:04 volumio volumio[962]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 30 22:10:04 volumio volumio[962]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 30 22:10:04 volumio volumio[962]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 30 22:10:04 volumio volumio[962]: at Parser.emit (events.js:315:20) Oct 30 22:10:04 volumio volumio[962]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 30 22:10:04 volumio volumio[962]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 30 22:10:04 volumio volumio[962]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 30 22:10:04 volumio volumio[962]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 30 22:10:04 volumio volumio[962]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 30 22:10:04 volumio volumio[962]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 30 22:10:04 volumio volumio[962]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 30 22:10:04 volumio volumio[962]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 30 22:10:04 volumio volumio[962]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 30 22:10:04 volumio volumio[962]: at IncomingMessage.emit (events.js:327:22) Oct 30 22:10:04 volumio volumio[962]: at endReadableNT (internal/streams/readable.js:1327:12) Oct 30 22:10:04 volumio volumio[962]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Oct 30 22:10:04 volumio volumio[962]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 30 22:10:05 volumio sudo[1668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-30 22:09 Oct 30 22:10:05 volumio sudo[1668]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"