Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetQueue Jan 12 21:22:03 volumio volumio[1250]: info: CoreStateMachine::getQueue Jan 12 21:22:03 volumio volumio[1250]: info: CorePlayQueue::getQueue Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/volumio/app/plugins/miscellanea/albumart/icons/fa-tags.svg' Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio go-librespot[1820]: time="2026-01-12T21:22:05+01:00" level=debug msg="fetched chunk 12/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::executeOnPlugin: cdplayer , handleBrowseUri Jan 12 21:22:09 volumio volumio[1250]: error: [CDPlayer]: Error while listing CD tracks Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:10 volumio go-librespot[1820]: time="2026-01-12T21:22:10+01:00" level=trace msg="sent dealer ping" Jan 12 21:22:11 volumio go-librespot[1820]: time="2026-01-12T21:22:11+01:00" level=trace msg="received dealer pong" Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio mpd_oled_plugin.sh[2061]: Failed to write to the i2c bus. Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio go-librespot[1820]: time="2026-01-12T21:22:17+01:00" level=debug msg="fetched chunk 13/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:22:17 volumio mpd_oled_plugin.sh[2061]: Failed to write to the i2c bus. Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:25 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:26 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:27 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:28 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio go-librespot[1820]: time="2026-01-12T21:22:29+01:00" level=debug msg="fetched chunk 14/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:29 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:30 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:31 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:32 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:33 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:34 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:35 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: error: error Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:36 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:37 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:38 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:39 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:40 volumio go-librespot[1820]: time="2026-01-12T21:22:40+01:00" level=trace msg="sent dealer ping" Jan 12 21:22:41 volumio go-librespot[1820]: time="2026-01-12T21:22:41+01:00" level=trace msg="received dealer pong" Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio go-librespot[1820]: time="2026-01-12T21:22:41+01:00" level=debug msg="fetched chunk 15/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:41 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio go-librespot[1820]: time="2026-01-12T21:22:42+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Jan 12 21:22:42 volumio go-librespot[1820]: time="2026-01-12T21:22:42+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Jan 12 21:22:42 volumio go-librespot[1820]: time="2026-01-12T21:22:42+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 323" Jan 12 21:22:42 volumio go-librespot[1820]: time="2026-01-12T21:22:42+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1368" Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:42 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:43 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:44 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:45 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:46 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:47 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:48 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:49 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:50 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:51 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:52 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio go-librespot[1820]: time="2026-01-12T21:22:53+01:00" level=debug msg="fetched chunk 16/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:53 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:54 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:55 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:56 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:57 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:58 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:22:59 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:00 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:01 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:02 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio go-librespot[1820]: time="2026-01-12T21:23:03+01:00" level=debug msg="fetched chunk 17/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:03 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:04 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:05 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:06 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:07 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:08 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:09 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:10 volumio go-librespot[1820]: time="2026-01-12T21:23:10+01:00" level=trace msg="sent dealer ping" Jan 12 21:23:11 volumio go-librespot[1820]: time="2026-01-12T21:23:11+01:00" level=trace msg="received dealer pong" Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:11 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:12 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:13 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:14 volumio go-librespot[1820]: time="2026-01-12T21:23:14+01:00" level=debug msg="fetched chunk 18/29, size: 524288" uri="spotify:track:67oyFnjJnn78fZP9KjeZx0" Jan 12 21:23:15 volumio go-librespot[1820]: time="2026-01-12T21:23:15+01:00" level=trace msg="received accesspoint ping" Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:15 volumio go-librespot[1820]: time="2026-01-12T21:23:15+01:00" level=debug msg="handling transfer player command from 285a9751e290c732ab994f9c04abb59803cd30ed" Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:15 volumio go-librespot[1820]: time="2026-01-12T21:23:15+01:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:37i9dQZF1E37qOFs3C2hJJ" Jan 12 21:23:15 volumio go-librespot[1820]: panic: runtime error: invalid memory address or nil pointer dereference Jan 12 21:23:15 volumio go-librespot[1820]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x8 pc=0x45e5f4] Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 85 gp=0x20847e8 m=5 mp=0x2080008 [running]: Jan 12 21:23:15 volumio go-librespot[1820]: panic({0x6cfb00, 0xca02c0}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x23055fc sp=0x23055a8 pc=0x58bf8 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.panicmem(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/panic.go:261 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.sigpanic() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x230562c sp=0x23055fc pc=0x74be4 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot.ContextTrackToProvidedTrack({0x79177a, 0x5}, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/ids.go:25 +0x14 fp=0x2305690 sp=0x2305630 pc=0x45e5f4 Jan 12 21:23:15 volumio go-librespot[1820]: main.(*AppPlayer).handlePlayerCommand(0x23c2230, {0x8874f0, 0xd0ad00}, {0x33e08bc5, {0x23ce420, 0x28}, {{0x23ccb10, 0x8}, {0x0, 0x0}, ...}}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/player.go:213 +0x18b4 fp=0x23057b0 sp=0x2305690 pc=0x642a6c Jan 12 21:23:15 volumio go-librespot[1820]: main.(*AppPlayer).handleDealerRequest(0x23c2230, {0x8874f0, 0xd0ad00}, {0x237e340, {0x23ce3c0, 0x24}, {0x33e08bc5, {0x23ce420, 0x28}, {{0x23ccb10, ...}, ...}}}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/player.go:374 +0x11c fp=0x23058e4 sp=0x23057b0 pc=0x6436ac Jan 12 21:23:15 volumio go-librespot[1820]: main.(*AppPlayer).Run(0x23c2230, {0x8874f0, 0xd0ad00}, 0x207d9c0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/player.go:602 +0x690 fp=0x2305fd8 sp=0x23058e4 pc=0x645bdc Jan 12 21:23:15 volumio go-librespot[1820]: main.(*App).withAppPlayer.func3.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:358 +0x40 fp=0x2305fec sp=0x2305fd8 pc=0x63e298 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2305fec sp=0x2305fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:358 +0x584 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 1 gp=0x2002128 m=nil [select, 38 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24d1d30 sp=0x24d1d1c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x24d1e30, 0x22b9dfc, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24d1dd0 sp=0x24d1d30 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x2028de0, 0x24d1ec0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x24d1e74 sp=0x24d1dd0 pc=0x5a8088 Jan 12 21:23:15 volumio go-librespot[1820]: main.(*App).withAppPlayer(0x20bab80, {0x8874f0, 0xd0ad00}, 0x20957d0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:321 +0x554 fp=0x24d1f00 sp=0x24d1e74 pc=0x63db54 Jan 12 21:23:15 volumio go-librespot[1820]: main.(*App).Zeroconf(...) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:170 Jan 12 21:23:15 volumio go-librespot[1820]: main.main() Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:549 +0x7f4 fp=0x24d1fa8 sp=0x24d1f00 pc=0x640120 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.main() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x24d1fec sp=0x24d1fa8 pc=0x5cb30 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24d1fec sp=0x24d1fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 18 gp=0x2084248 m=nil [force gc (idle), 38 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407d4 sp=0x20407c0 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goparkunlock(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.forcegchelper() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x20407ec sp=0x20407d4 pc=0x5cefc Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.init.5 in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 19 gp=0x2084368 m=nil [GC sweep wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fc4 sp=0x2040fb0 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goparkunlock(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.bgsweep(0x2092000) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x2040fe4 sp=0x2040fc4 pc=0x45848 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcenable.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x35968 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcenable in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 20 gp=0x2084488 m=nil [GC scavenge wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20417b4 sp=0x20417a0 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goparkunlock(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.(*scavengerState).park(0xce0208) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x20417c8 sp=0x20417b4 pc=0x42b68 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.bgscavenge(0x2092000) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x20417e4 sp=0x20417c8 pc=0x432a0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcenable.gowrap2() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x20417ec sp=0x20417e4 pc=0x35914 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcenable in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 34 gp=0x2118008 m=nil [finalizer wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x211e78c sp=0x211e778 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.runfinq() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x211e7ec sp=0x211e78c pc=0x34754 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x211e7ec sp=0x211e7ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.createfing in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 23 gp=0x2085d48 m=nil [IO wait, 41 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bff18, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042528 sp=0x2042514 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bff08, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2042540 sp=0x2042528 pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bff08, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2042554 sp=0x2042540 pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x20d5198, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2042568 sp=0x2042554 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Accept(0x20d5180) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x20425b0 sp=0x2042568 pc=0x10eb84 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).accept(0x20d5180) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2042618 sp=0x20425b0 pc=0x17e4c4 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPListener).accept(0x20ac6f0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2042630 sp=0x2042618 pc=0x196900 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPListener).Accept(0x20ac6f0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x204264c sp=0x2042630 pc=0x19596c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*onceCloseListener).Accept(0x207e340) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x34 fp=0x2042664 sp=0x204264c pc=0x362b54 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*Server).Serve(0x20780a8, {0x886cb8, 0x20ac6f0}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x20426fc sp=0x2042664 pc=0x341210 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.Serve(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2794 Jan 12 21:23:15 volumio go-librespot[1820]: main.(*ConcreteApiServer).serve(0x20d0f60) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x20427e4 sp=0x20426fc pc=0x6330b8 Jan 12 21:23:15 volumio go-librespot[1820]: main.NewApiServer.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x20427ec sp=0x20427e4 pc=0x631de4 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by main.NewApiServer in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/api_server.go:296 +0x310 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 53 gp=0x224c008 m=nil [IO wait, 41 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfbf8, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205687c sp=0x2056868 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfbe8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2056894 sp=0x205687c pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfbe8, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20568a8 sp=0x2056894 pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x254f0a8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20568bc sp=0x20568a8 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Read(0x254f090, {0x216a000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2056904 sp=0x20568bc pc=0x10b504 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).Read(0x254f090, {0x216a000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2056930 sp=0x2056904 pc=0x17c5d0 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*conn).Read(0x24addc0, {0x216a000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x205695c sp=0x2056930 pc=0x18caec Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPConn).Read(0x24addc0, {0x216a000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x44 fp=0x205697c sp=0x205695c pc=0x19fd40 Jan 12 21:23:15 volumio go-librespot[1820]: io.(*multiReader).Read(0x2537590, {0x216a000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x20569a8 sp=0x205697c pc=0x105528 Jan 12 21:23:15 volumio go-librespot[1820]: bufio.(*Reader).fill(0x209acf0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x20569cc sp=0x20569a8 pc=0x2bddb4 Jan 12 21:23:15 volumio go-librespot[1820]: bufio.(*Reader).ReadByte(0x209acf0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x20569d8 sp=0x20569cc pc=0x2be638 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.readFrameHeader(0x209acf0, {0x21ee3f8, 0x8, 0x8}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2056a18 sp=0x20569d8 pc=0x47788c Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x21ee3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2056ad8 sp=0x2056a18 pc=0x47a4f0 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).readLoop(0x21ee3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2056b9c sp=0x2056ad8 pc=0x479fd8 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).reader(0x21ee3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2056c28 sp=0x2056b9c pc=0x47b1ec Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).Read(0x21ee3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2056c50 sp=0x2056c28 pc=0x4798bc Jan 12 21:23:15 volumio go-librespot[1820]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2056ccc sp=0x2056c50 pc=0x633988 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.HandlerFunc.ServeHTTP(0x2030190, {0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2056cdc sp=0x2056ccc pc=0x33d7f4 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*ServeMux).ServeHTTP(0x2074000, {0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2056d18 sp=0x2056cdc pc=0x33f654 Jan 12 21:23:15 volumio go-librespot[1820]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2056d40 sp=0x2056d18 pc=0x633388 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.HandlerFunc.ServeHTTP(0x200e180, {0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2056d50 sp=0x2056d40 pc=0x33d7f4 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.serverHandler.ServeHTTP({0x20780a8}, {0x886dec, 0x2078a08}, 0x2078968) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2056d6c sp=0x2056d50 pc=0x340de4 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*conn).serve(0x2396c60, {0x887510, 0x2026198}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2056fdc sp=0x2056d6c pc=0x33c034 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*Server).Serve.gowrap3() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2056fec sp=0x2056fdc pc=0x341688 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2056fec sp=0x2056fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by net/http.(*Server).Serve in goroutine 23 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 91 gp=0x224c128 m=nil [chan receive]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3f90, 0x237e370, 0xe, 0x7, 0x2) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x231bd60 sp=0x231bd4c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv(0x237e340, 0x231bddb, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x231bd9c sp=0x231bd60 pc=0x20078 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv1(0x237e340, 0x231bddb) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x231bdb0 sp=0x231bd9c pc=0x1fb98 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleRequest(0x2488588, 0x24d2730) Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/recv.go:237 +0x1f8 fp=0x231bf2c sp=0x231bdb0 pc=0x533ab4 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2488588) Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:213 +0x4d8 fp=0x231bfe4 sp=0x231bf2c pc=0x5321e4 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:125 +0x28 fp=0x231bfec sp=0x231bfe4 pc=0x53383c Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x231bfec sp=0x231bfec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 85 Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:125 +0x98 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 35 gp=0x2118128 m=nil [GC worker (idle)]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3fbc, 0x23de318, 0x1a, 0xa, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044790 sp=0x204477c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcBgMarkWorker() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20447ec sp=0x2044790 pc=0x38590 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20447ec sp=0x20447ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 29 gp=0x224c6c8 m=nil [GC worker (idle)]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3fbc, 0x202a1c8, 0x1a, 0xa, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042f90 sp=0x2042f7c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcBgMarkWorker() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2042fec sp=0x2042f90 pc=0x38590 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2042fec sp=0x2042fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 30 gp=0x224c7e8 m=nil [GC worker (idle)]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3fbc, 0x2135518, 0x1a, 0xa, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2043790 sp=0x204377c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcBgMarkWorker() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20437ec sp=0x2043790 pc=0x38590 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20437ec sp=0x20437ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 36 gp=0x2118248 m=nil [GC worker (idle)]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3fbc, 0x2135530, 0x1a, 0xa, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x211ef90 sp=0x211ef7c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gcBgMarkWorker() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x211efec sp=0x211ef90 pc=0x38590 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x211efec sp=0x211efec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 33 gp=0x224cea8 m=nil [chan receive, 41 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3f90, 0x2092230, 0xe, 0x7, 0x2) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x211f748 sp=0x211f734 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv(0x2092200, 0x211f7d0, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x211f784 sp=0x211f748 pc=0x20078 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv2(0x2092200, 0x211f7d0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x211f798 sp=0x211f784 pc=0x1fbbc Jan 12 21:23:15 volumio go-librespot[1820]: main.(*App).withAppPlayer.func1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:268 +0x68 fp=0x211f7ec sp=0x211f798 pc=0x63e794 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x211f7ec sp=0x211f7ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by main.(*App).withAppPlayer in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:265 +0x464 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 66 gp=0x224cfc8 m=nil [chan receive, 41 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e3f90, 0x20921f0, 0xe, 0x7, 0x2) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x211ff40 sp=0x211ff2c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv(0x20921c0, 0x211ffe0, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x211ff7c sp=0x211ff40 pc=0x20078 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.chanrecv2(0x20921c0, 0x211ffe0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x211ff90 sp=0x211ff7c pc=0x1fbbc Jan 12 21:23:15 volumio go-librespot[1820]: main.(*App).withAppPlayer.func2() Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:284 +0x6c fp=0x211ffec sp=0x211ff90 pc=0x63e330 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x211ffec sp=0x211ffec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by main.(*App).withAppPlayer in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/cmd/daemon/main.go:281 +0x504 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 67 gp=0x224d0e8 m=nil [IO wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfd38, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205be84 sp=0x205be70 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfd28, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x205be9c sp=0x205be84 pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfd28, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x205beb0 sp=0x205be9c pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x23c3328, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x205bec4 sp=0x205beb0 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).RawRead(0x23c3310, 0x235f470) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x205bef0 sp=0x205bec4 pc=0x10f788 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*rawConn).Read(0x23904a0, 0x235f470) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x205bf10 sp=0x205bef0 pc=0x190d28 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x23bb970, 0x235f3b0, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x205bf40 sp=0x205bf10 pc=0x548824 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x23d9e68, {0x24b4000, 0x10000, 0x10000}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x205bf9c sp=0x205bf40 pc=0x54c80c Jan 12 21:23:15 volumio go-librespot[1820]: github.com/grandcat/zeroconf.(*Server).recv4(0x2283840, 0x23d9e60) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x205bfe0 sp=0x205bf9c pc=0x5a309c Jan 12 21:23:15 volumio go-librespot[1820]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x205bfec sp=0x205bfe0 pc=0x5a2d54 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x205bfec sp=0x205bfec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 31 Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 68 gp=0x224d208 m=nil [IO wait, 41 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfc98, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2120e5c sp=0x2120e48 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfc88, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2120e74 sp=0x2120e5c pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfc88, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2120e88 sp=0x2120e74 pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x23c3378, 0x72, 0x0) Jan 12 21:23:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2120e9c sp=0x2120e88 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).RawRead(0x23c3360, 0x2250030) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2120ec8 sp=0x2120e9c pc=0x10f788 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*rawConn).Read(0x23904c8, 0x2250030) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2120ee8 sp=0x2120ec8 pc=0x190d28 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x23bb980, 0x2250000, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2120f18 sp=0x2120ee8 pc=0x548824 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 12 21:23:15 volumio go-librespot[1820]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x23d9e98, {0x223c000, 0x10000, 0x10000}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x2120f9c sp=0x2120f18 pc=0x550194 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/grandcat/zeroconf.(*Server).recv6(0x2283840, 0x23d9e90) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2120fe0 sp=0x2120f9c pc=0x5a3290 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2120fec sp=0x2120fe0 pc=0x5a2cf8 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2120fec sp=0x2120fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 31 Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 4 gp=0x2118368 m=nil [IO wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfdd8, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x229b5e8 sp=0x229b5d4 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfdc8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x229b600 sp=0x229b5e8 pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfdc8, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x229b614 sp=0x229b600 pc=0x8f4a8 Jan 12 21:23:15 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 12 21:23:15 volumio volumio[1250]: info: Connection to go-librespot Websocket closed Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x23c32d8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x229b628 sp=0x229b614 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Accept(0x23c32c0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x229b670 sp=0x229b628 pc=0x10eb84 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).accept(0x23c32c0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x229b6d8 sp=0x229b670 pc=0x17e4c4 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPListener).accept(0x253d458) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x229b6f0 sp=0x229b6d8 pc=0x196900 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPListener).Accept(0x253d458) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x229b70c sp=0x229b6f0 pc=0x19596c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*onceCloseListener).Accept(0x248e040) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x34 fp=0x229b724 sp=0x229b70c pc=0x362b54 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*Server).Serve(0x20b0008, {0x886cb8, 0x253d458}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x229b7bc sp=0x229b724 pc=0x341210 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.Serve(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/server.go:2794 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 12 21:23:15 volumio go-librespot[1820]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x229b7ec sp=0x229b7bc pc=0x5a8184 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x229b7ec sp=0x229b7ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/zeroconf/zeroconf.go:280 +0x160 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 55 gp=0x2003448 m=nil [select, 3 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21206a8 sp=0x2120694 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x21207a4, 0x212076c, 0x0, 0x0, 0x5, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2120748 sp=0x21206a8 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x21ee3c8) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x21207e4 sp=0x2120748 pc=0x474738 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.newConn.gowrap1() Jan 12 21:23:15 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x21207ec sp=0x21207e4 pc=0x47432c Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21207ec sp=0x21207ec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by nhooyr.io/websocket.newConn in goroutine 53 Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 88 gp=0x2084908 m=nil [select]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2046ea8 sp=0x2046e94 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x2046fa4, 0x2046f6c, 0x0, 0x0, 0x5, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2046f48 sp=0x2046ea8 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x21eea08) Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2046fe4 sp=0x2046f48 pc=0x474738 Jan 12 21:23:15 volumio go-librespot[1820]: nhooyr.io/websocket.newConn.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2046fec sp=0x2046fe4 pc=0x47432c Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2046fec sp=0x2046fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by nhooyr.io/websocket.newConn in goroutine 85 Jan 12 21:23:15 volumio go-librespot[1820]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 92 gp=0x2118c68 m=nil [select]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2318e98 sp=0x2318e84 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x2318fd4, 0x2318f60, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2318f38 sp=0x2318e98 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2488588) Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:138 +0x8c fp=0x2318fe4 sp=0x2318f38 pc=0x5318dc Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:129 +0x28 fp=0x2318fec sp=0x2318fe4 pc=0x5337e8 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2318fec sp=0x2318fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 85 Jan 12 21:23:15 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 15.429s CPU time. Jan 12 21:23:15 volumio go-librespot[1820]: /src/dealer/dealer.go:129 +0x15c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 84 gp=0x2168488 m=nil [select, 3 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24cce74 sp=0x24cce60 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x24ccf74, 0x24ccf3c, 0x0, 0x0, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24ccf14 sp=0x24cce74 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x20d5e00) Jan 12 21:23:15 volumio go-librespot[1820]: /src/player/player.go:201 +0x1fc fp=0x24ccfe4 sp=0x24ccf14 pc=0x513660 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/player/player.go:183 +0x28 fp=0x24ccfec sp=0x24ccfe4 pc=0x513240 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24ccfec sp=0x24ccfec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 12 21:23:15 volumio go-librespot[1820]: /src/player/player.go:183 +0x218 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 90 gp=0x21685a8 m=nil [select]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2317eb8 sp=0x2317ea4 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x2317fd4, 0x2317f7c, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2317f58 sp=0x2317eb8 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x20f04d8) Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:355 +0x8c fp=0x2317fe4 sp=0x2317f58 pc=0x48cb50 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:264 +0x28 fp=0x2317fec sp=0x2317fe4 pc=0x48c248 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2317fec sp=0x2317fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 85 Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:264 +0x15c Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 71 gp=0x2118fc8 m=nil [select, 3 minutes]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2059cb0 sp=0x2059c9c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x2059e14, 0x2059d80, 0x0, 0x0, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2059d50 sp=0x2059cb0 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).recvLoop(0x248fc00) Jan 12 21:23:15 volumio go-librespot[1820]: /src/audio/provider.go:63 +0x118 fp=0x2059fe4 sp=0x2059d50 pc=0x494340 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/audio/provider.go:53 +0x28 fp=0x2059fec sp=0x2059fe4 pc=0x49508c Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2059fec sp=0x2059fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/audio.(*KeyProvider).Request.(*KeyProvider).startReceiving.func1 in goroutine 85 Jan 12 21:23:15 volumio go-librespot[1820]: /src/audio/provider.go:53 +0x60 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 165 gp=0x2119328 m=nil [sleep]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4120, 0x209b5c0, 0x13, 0xe, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2560f6c sp=0x2560f58 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: time.Sleep(0x5e9c4cd) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/time.go:195 +0x158 fp=0x2560f88 sp=0x2560f6c pc=0x91cc4 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/output.(*alsaOutput).outputLoop(0x2074480, 0xb5541950) Jan 12 21:23:15 volumio go-librespot[1820]: /src/output/driver-alsa.go:381 +0xf8 fp=0x2560fe0 sp=0x2560f88 pc=0x4a7738 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/output.(*alsaOutput).setupPcm.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/output/driver-alsa.go:233 +0x30 fp=0x2560fec sp=0x2560fe0 pc=0x4a5dd8 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2560fec sp=0x2560fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/output.(*alsaOutput).setupPcm in goroutine 84 Jan 12 21:23:15 volumio go-librespot[1820]: /src/output/driver-alsa.go:233 +0x670 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 443817 gp=0x22a85a8 m=nil [select]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24cdeb0 sp=0x24cde9c pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.selectgo(0x24cdfac, 0x24cdf78, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24cdf50 sp=0x24cdeb0 pc=0x70e78 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*persistConn).writeLoop(0x23082c8) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x24cdfe4 sp=0x24cdf50 pc=0x357c1c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*Transport).dialConn.gowrap3() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x24cdfec sp=0x24cdfe4 pc=0x354a28 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24cdfec sp=0x24cdfec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by net/http.(*Transport).dialConn in goroutine 445154 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 566371 gp=0x22f0d88 m=nil [IO wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfe78, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2054ad4 sp=0x2054ac0 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfe68, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2054aec sp=0x2054ad4 pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfe68, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2054b00 sp=0x2054aec pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x24d3058, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2054b14 sp=0x2054b00 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Read(0x24d3040, {0x222d000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2054b5c sp=0x2054b14 pc=0x10b504 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).Read(0x24d3040, {0x222d000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2054b88 sp=0x2054b5c pc=0x17c5d0 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*conn).Read(0x24acdd8, {0x222d000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2054bb4 sp=0x2054b88 pc=0x18caec Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPConn).Read(0x24acdd8, {0x222d000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x44 fp=0x2054bd4 sp=0x2054bb4 pc=0x19fd40 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*atLeastReader).Read(0x2373890, {0x222d000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2054c00 sp=0x2054bd4 pc=0x27c680 Jan 12 21:23:15 volumio go-librespot[1820]: bytes.(*Buffer).ReadFrom(0x22e660c, {0x883674, 0x2373890}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2054c3c sp=0x2054c00 pc=0x139bd0 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readFromUntil(0x22e6488, {0x882848, 0x24acdd8}, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2054c64 sp=0x2054c3c pc=0x27c8dc Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readRecordOrCCS(0x22e6488, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2054dc8 sp=0x2054c64 pc=0x279fdc Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).Read(0x22e6488, {0x2187000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2054df8 sp=0x2054dc8 pc=0x27fe4c Jan 12 21:23:15 volumio go-librespot[1820]: bufio.(*Reader).Read(0x2ae6930, {0x20ba924, 0x9, 0x9}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x2054e1c sp=0x2054df8 pc=0x2be440 Jan 12 21:23:15 volumio go-librespot[1820]: io.ReadAtLeast({0x882a3c, 0x2ae6930}, {0x20ba924, 0x9, 0x9}, 0x9) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2054e48 sp=0x2054e1c pc=0x1042c0 Jan 12 21:23:15 volumio go-librespot[1820]: io.ReadFull(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.http2readFrameHeader({0x20ba924, 0x9, 0x9}, {0x882a3c, 0x2ae6930}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x2054e70 sp=0x2054e48 pc=0x2f733c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*http2Framer).ReadFrame(0x20ba900) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x2054eec sp=0x2054e70 pc=0x2f7ad4 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*http2clientConnReadLoop).run(0x2054fdc) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x2054fa4 sp=0x2054eec pc=0x31c2f0 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*http2ClientConn).readLoop(0x2232108) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x2054fe4 sp=0x2054fa4 pc=0x31b7e4 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x2054fec sp=0x2054fe4 pc=0x314830 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2054fec sp=0x2054fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by net/http.(*http2Transport).newClientConn in goroutine 566370 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 443816 gp=0x2809688 m=nil [IO wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bf6f8, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2f3aafc sp=0x2f3aae8 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bf6e8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2f3ab14 sp=0x2f3aafc pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bf6e8, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2f3ab28 sp=0x2f3ab14 pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x24d3148, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2f3ab3c sp=0x2f3ab28 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Read(0x24d3130, {0x360a000, 0xa000, 0xa000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2f3ab84 sp=0x2f3ab3c pc=0x10b504 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).Read(0x24d3130, {0x360a000, 0xa000, 0xa000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2f3abb0 sp=0x2f3ab84 pc=0x17c5d0 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*conn).Read(0x24ac3d8, {0x360a000, 0xa000, 0xa000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2f3abdc sp=0x2f3abb0 pc=0x18caec Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPConn).Read(0x24ac3d8, {0x360a000, 0xa000, 0xa000}) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x44 fp=0x2f3abfc sp=0x2f3abdc pc=0x19fd40 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*atLeastReader).Read(0x210c980, {0x360a000, 0xa000, 0xa000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x2f3ac28 sp=0x2f3abfc pc=0x27c680 Jan 12 21:23:15 volumio go-librespot[1820]: bytes.(*Buffer).ReadFrom(0x222114c, {0x883674, 0x210c980}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x2f3ac64 sp=0x2f3ac28 pc=0x139bd0 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readFromUntil(0x2220fc8, {0x882848, 0x24ac3d8}, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x2f3ac8c sp=0x2f3ac64 pc=0x27c8dc Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readRecordOrCCS(0x2220fc8, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x2f3adf0 sp=0x2f3ac8c pc=0x279fdc Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:15 volumio go-librespot[1820]: crypto/tls.(*Conn).Read(0x2220fc8, {0x31f6000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x2f3ae20 sp=0x2f3adf0 pc=0x27fe4c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*persistConn).Read(0x23082c8, {0x31f6000, 0x1000, 0x1000}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x2f3ae54 sp=0x2f3ae20 pc=0x3555cc Jan 12 21:23:15 volumio go-librespot[1820]: bufio.(*Reader).fill(0x30f9c80) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x2f3ae78 sp=0x2f3ae54 pc=0x2bddb4 Jan 12 21:23:15 volumio go-librespot[1820]: bufio.(*Reader).Peek(0x30f9c80, 0x1) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x2f3ae84 sp=0x2f3ae78 pc=0x2bdf38 Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*persistConn).readLoop(0x23082c8) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x2f3afe4 sp=0x2f3ae84 pc=0x35615c Jan 12 21:23:15 volumio go-librespot[1820]: net/http.(*Transport).dialConn.gowrap2() Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x2f3afec sp=0x2f3afe4 pc=0x354a7c Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2f3afec sp=0x2f3afec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by net/http.(*Transport).dialConn in goroutine 445154 Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Jan 12 21:23:15 volumio go-librespot[1820]: goroutine 63719 gp=0x2950fc8 m=nil [IO wait]: Jan 12 21:23:15 volumio go-librespot[1820]: runtime.gopark(0x7e40f0, 0xb56bfab8, 0x2, 0x2, 0x5) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2316d14 sp=0x2316d00 pc=0x5d0c0 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.netpollblock(0xb56bfaa8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2316d2c sp=0x2316d14 pc=0x548cc Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.runtime_pollWait(0xb56bfaa8, 0x72) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2316d40 sp=0x2316d2c pc=0x8f4a8 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).wait(0x22fe2e8, 0x72, 0x0) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2316d54 sp=0x2316d40 pc=0x10a318 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:15 volumio go-librespot[1820]: internal/poll.(*FD).Read(0x22fe2d0, {0x239c488, 0x3, 0x3}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2316d9c sp=0x2316d54 pc=0x10b504 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*netFD).Read(0x22fe2d0, {0x239c488, 0x3, 0x3}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2316dc8 sp=0x2316d9c pc=0x17c5d0 Jan 12 21:23:15 volumio go-librespot[1820]: net.(*conn).Read(0x2cb3d58, {0x239c488, 0x3, 0x3}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2316df4 sp=0x2316dc8 pc=0x18caec Jan 12 21:23:15 volumio go-librespot[1820]: net.(*TCPConn).Read(0x2cb3d58, {0x239c488, 0x3, 0x3}) Jan 12 21:23:15 volumio go-librespot[1820]: :1 +0x44 fp=0x2316e14 sp=0x2316df4 pc=0x19fd40 Jan 12 21:23:15 volumio go-librespot[1820]: io.ReadAtLeast({0x882848, 0x2cb3d58}, {0x239c488, 0x3, 0x3}, 0x3) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2316e40 sp=0x2316e14 pc=0x1042c0 Jan 12 21:23:15 volumio go-librespot[1820]: io.ReadFull(...) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x24950c0, {0x8874f0, 0xd0ad00}) Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/shannon.go:124 +0x3a0 fp=0x2316f04 sp=0x2316e40 pc=0x48faf4 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x20f04d8) Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:276 +0x68 fp=0x2316fe4 sp=0x2316f04 pc=0x48c330 Jan 12 21:23:15 volumio go-librespot[1820]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:390 +0x28 fp=0x2316fec sp=0x2316fe4 pc=0x48d010 Jan 12 21:23:15 volumio go-librespot[1820]: runtime.goexit({}) Jan 12 21:23:15 volumio go-librespot[1820]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2316fec sp=0x2316fec pc=0x93d6c Jan 12 21:23:15 volumio go-librespot[1820]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 89 Jan 12 21:23:15 volumio go-librespot[1820]: /src/ap/ap.go:390 +0x120 Jan 12 21:23:15 volumio go-librespot[1819]: Aborted Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:15 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:16 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:17 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: Initializing connection to go-librespot Websocket Jan 12 21:23:18 volumio volumio[1250]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 12 21:23:18 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jan 12 21:23:18 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 12 21:23:18 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 15.429s CPU time. Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:18 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 12 21:23:18 volumio go-librespot[6634]: go-librespot daemon starting... Jan 12 21:23:18 volumio go-librespot[6635]: time="2026-01-12T21:23:18+01:00" level=info msg="running go-librespot 0.4.0" Jan 12 21:23:18 volumio go-librespot[6635]: time="2026-01-12T21:23:18+01:00" level=debug msg="app state loaded" Jan 12 21:23:18 volumio go-librespot[6635]: time="2026-01-12T21:23:18+01:00" level=debug msg="stored credentials not found" Jan 12 21:23:18 volumio go-librespot[6635]: time="2026-01-12T21:23:18+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 12 21:23:19 volumio go-librespot[6635]: time="2026-01-12T21:23:19+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]" Jan 12 21:23:19 volumio go-librespot[6635]: time="2026-01-12T21:23:19+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]" Jan 12 21:23:19 volumio go-librespot[6635]: time="2026-01-12T21:23:19+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]" Jan 12 21:23:19 volumio go-librespot[6635]: time="2026-01-12T21:23:19+01:00" level=info msg="zeroconf server listening on port 33995" Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:19 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:20 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: Initializing connection to go-librespot Websocket Jan 12 21:23:21 volumio go-librespot[6635]: time="2026-01-12T21:23:21+01:00" level=debug msg="new websocket client" Jan 12 21:23:21 volumio volumio[1250]: info: Connection to go-librespot Websocket established Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:21 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="obtained new client token: AAA5xr9tt/LRKr0J7znnkyjFFHcbCqVY1/5GwQcoMgDR9Ge6io+GY0BQSgNLm/jNb1j58XciOlniPJnC5VdiFdG97RAZhGXw3Ka7/1oP2Qq26fmZ3JzpHsnII9kUhyMqQrYNAZMTHVi+cMykA8GMd1kEDUwlqSfJcibOLlASqKtM4iRA/HDPRe42pZXBqs1E1Dzt6qRDZ7NbztfqWV7FKCl1aO/w2DZ9ZuDFXiXFI996aOUXJmW0a1Mw" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="completed keyexchange" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="completed challenge" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=info msg="authenticated AP" username="31************************ky" Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=info msg="authenticated Login5" username="31************************ky" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=info msg="accepted zeroconf from DELL-HOME" username="31************************ky" Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="dealer connection opened" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=trace msg="starting accesspoint recv loop" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=trace msg="starting dealer recv loop" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=trace msg="received accesspoint ping" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=debug msg="received connection id: NzUwMzJiNjUtMmU5...NTYyM0M1RTExNw==" Jan 12 21:23:22 volumio go-librespot[6635]: time="2026-01-12T21:23:22+01:00" level=trace msg="received accesspoint pong ack" Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:22 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=debug msg="update volume requested to 65535/65535" Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=trace msg="emitting websocket event: volume" Jan 12 21:23:23 volumio volumio[1250]: SPOTIFY: received: {"type":"volume","data":{"value":100,"max":100}} Jan 12 21:23:23 volumio volumio[1250]: SPOTIFY: RECEIVED SPOTIFY VOLUME 100 Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=debug msg="handling transfer player command from 285a9751e290c732ab994f9c04abb59803cd30ed" Jan 12 21:23:23 volumio go-librespot[6635]: time="2026-01-12T21:23:23+01:00" level=debug msg="resolved context of track" uri="spotify:station:playlist:37i9dQZF1E37qOFs3C2hJJ" Jan 12 21:23:23 volumio go-librespot[6635]: panic: runtime error: invalid memory address or nil pointer dereference Jan 12 21:23:23 volumio go-librespot[6635]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x8 pc=0x45e5f4] Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 33 gp=0x20857a8 m=6 mp=0x2080588 [running]: Jan 12 21:23:23 volumio go-librespot[6635]: panic({0x6cfb00, 0xca02c0}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x218b5fc sp=0x218b5a8 pc=0x58bf8 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.panicmem(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/panic.go:261 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.sigpanic() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/signal_unix.go:881 +0x39c fp=0x218b62c sp=0x218b5fc pc=0x74be4 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot.ContextTrackToProvidedTrack({0x79177a, 0x5}, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /src/ids.go:25 +0x14 fp=0x218b690 sp=0x218b630 pc=0x45e5f4 Jan 12 21:23:23 volumio go-librespot[6635]: main.(*AppPlayer).handlePlayerCommand(0x2286370, {0x8874f0, 0xd0ad00}, {0x33e0acd2, {0x23eb260, 0x28}, {{0x22d4830, 0x8}, {0x0, 0x0}, ...}}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/player.go:213 +0x18b4 fp=0x218b7b0 sp=0x218b690 pc=0x642a6c Jan 12 21:23:23 volumio go-librespot[6635]: main.(*AppPlayer).handleDealerRequest(0x2286370, {0x8874f0, 0xd0ad00}, {0x21d4080, {0x23ea690, 0x24}, {0x33e0acd2, {0x23eb260, 0x28}, {{0x22d4830, ...}, ...}}}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/player.go:374 +0x11c fp=0x218b8e4 sp=0x218b7b0 pc=0x6436ac Jan 12 21:23:23 volumio go-librespot[6635]: main.(*AppPlayer).Run(0x2286370, {0x8874f0, 0xd0ad00}, 0x23e72c0) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/player.go:602 +0x690 fp=0x218bfd8 sp=0x218b8e4 pc=0x645bdc Jan 12 21:23:23 volumio go-librespot[6635]: main.(*App).withAppPlayer.func3.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:358 +0x40 fp=0x218bfec sp=0x218bfd8 pc=0x63e298 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x218bfec sp=0x218bfec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by main.(*App).withAppPlayer.func3 in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:358 +0x584 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 1 gp=0x2002128 m=nil [select]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21afd30 sp=0x21afd1c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.selectgo(0x21afe30, 0x2189dfc, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x21afdd0 sp=0x21afd30 pc=0x70e78 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x20b44e0, 0x21afec0) Jan 12 21:23:23 volumio go-librespot[6635]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x21afe74 sp=0x21afdd0 pc=0x5a8088 Jan 12 21:23:23 volumio go-librespot[6635]: main.(*App).withAppPlayer(0x20ceb80, {0x8874f0, 0xd0ad00}, 0x2091818) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:321 +0x554 fp=0x21aff00 sp=0x21afe74 pc=0x63db54 Jan 12 21:23:23 volumio go-librespot[6635]: main.(*App).Zeroconf(...) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:170 Jan 12 21:23:23 volumio go-librespot[6635]: main.main() Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:549 +0x7f4 fp=0x21affa8 sp=0x21aff00 pc=0x640120 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.main() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x21affec sp=0x21affa8 pc=0x5cb30 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21affec sp=0x21affec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 2 gp=0x20027e8 m=nil [force gc (idle)]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044fd4 sp=0x2044fc0 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goparkunlock(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.forcegchelper() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2044fec sp=0x2044fd4 pc=0x5cefc Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2044fec sp=0x2044fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.init.5 in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 18 gp=0x2084248 m=nil [GC sweep wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407c4 sp=0x20407b0 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goparkunlock(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.bgsweep(0x206c000) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x20407e4 sp=0x20407c4 pc=0x45848 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcenable.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x20407ec sp=0x20407e4 pc=0x35968 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcenable in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 19 gp=0x2084368 m=nil [GC scavenge wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fb4 sp=0x2040fa0 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goparkunlock(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:408 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.(*scavengerState).park(0xce0208) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2040fc8 sp=0x2040fb4 pc=0x42b68 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.bgscavenge(0x206c000) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2040fe4 sp=0x2040fc8 pc=0x432a0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcenable.gowrap2() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x35914 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcenable in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 20 gp=0x2084908 m=nil [finalizer wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x204178c sp=0x2041778 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.runfinq() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x20417ec sp=0x204178c pc=0x34754 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.createfing in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 23 gp=0x21a6008 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061f08, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2041d28 sp=0x2041d14 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061ef8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2041d40 sp=0x2041d28 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061ef8, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2041d54 sp=0x2041d40 pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x20af238, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2041d68 sp=0x2041d54 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Accept(0x20af220) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x2041db0 sp=0x2041d68 pc=0x10eb84 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).accept(0x20af220) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x2041e18 sp=0x2041db0 pc=0x17e4c4 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPListener).accept(0x20ba708) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x2041e30 sp=0x2041e18 pc=0x196900 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPListener).Accept(0x20ba708) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x2041e4c sp=0x2041e30 pc=0x19596c Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*onceCloseListener).Accept(0x207e340) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x34 fp=0x2041e64 sp=0x2041e4c pc=0x362b54 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*Server).Serve(0x207a0a8, {0x886cb8, 0x20ba708}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x2041efc sp=0x2041e64 pc=0x341210 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.Serve(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2794 Jan 12 21:23:23 volumio go-librespot[6635]: main.(*ConcreteApiServer).serve(0x20b5020) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x2041fe4 sp=0x2041efc pc=0x6330b8 Jan 12 21:23:23 volumio go-librespot[6635]: main.NewApiServer.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x2041fec sp=0x2041fe4 pc=0x631de4 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2041fec sp=0x2041fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by main.NewApiServer in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/api_server.go:296 +0x310 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 80 gp=0x21a6128 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061a08, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2056d14 sp=0x2056d00 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb60619f8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2056d2c sp=0x2056d14 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb60619f8, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2056d40 sp=0x2056d2c pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x2269738, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2056d54 sp=0x2056d40 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x2269720, {0x231ca0c, 0x3, 0x3}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2056d9c sp=0x2056d54 pc=0x10b504 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).Read(0x2269720, {0x231ca0c, 0x3, 0x3}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2056dc8 sp=0x2056d9c pc=0x17c5d0 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*conn).Read(0x2382008, {0x231ca0c, 0x3, 0x3}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2056df4 sp=0x2056dc8 pc=0x18caec Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPConn).Read(0x2382008, {0x231ca0c, 0x3, 0x3}) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x44 fp=0x2056e14 sp=0x2056df4 pc=0x19fd40 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadAtLeast({0x882848, 0x2382008}, {0x231ca0c, 0x3, 0x3}, 0x3) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x2056e40 sp=0x2056e14 pc=0x1042c0 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadFull(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x2391480, {0x8874f0, 0xd0ad00}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/ap/shannon.go:124 +0x3a0 fp=0x2056f04 sp=0x2056e40 pc=0x48faf4 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x21018c8) Jan 12 21:23:23 volumio go-librespot[6635]: /src/ap/ap.go:276 +0x68 fp=0x2056fe4 sp=0x2056f04 pc=0x48c330 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/ap/ap.go:260 +0x28 fp=0x2056fec sp=0x2056fe4 pc=0x48c29c Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2056fec sp=0x2056fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 33 Jan 12 21:23:23 volumio go-librespot[6635]: /src/ap/ap.go:260 +0x98 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 11 gp=0x21a6248 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061be8, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205787c sp=0x2057868 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061bd8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2057894 sp=0x205787c pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061bd8, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x20578a8 sp=0x2057894 pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x22689c8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x20578bc sp=0x20578a8 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x22689b0, {0x218d000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2057904 sp=0x20578bc pc=0x10b504 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).Read(0x22689b0, {0x218d000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2057930 sp=0x2057904 pc=0x17c5d0 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*conn).Read(0x2090ae0, {0x218d000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x205795c sp=0x2057930 pc=0x18caec Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPConn).Read(0x2090ae0, {0x218d000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x44 fp=0x205797c sp=0x205795c pc=0x19fd40 Jan 12 21:23:23 volumio go-librespot[6635]: io.(*multiReader).Read(0x233a690, {0x218d000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x20579a8 sp=0x205797c pc=0x105528 Jan 12 21:23:23 volumio go-librespot[6635]: bufio.(*Reader).fill(0x22729f0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x20579cc sp=0x20579a8 pc=0x2bddb4 Jan 12 21:23:23 volumio go-librespot[6635]: bufio.(*Reader).ReadByte(0x22729f0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x20579d8 sp=0x20579cc pc=0x2be638 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.readFrameHeader(0x22729f0, {0x217c3f8, 0x8, 0x8}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2057a18 sp=0x20579d8 pc=0x47788c Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x217c3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2057ad8 sp=0x2057a18 pc=0x47a4f0 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).readLoop(0x217c3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2057b9c sp=0x2057ad8 pc=0x479fd8 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).reader(0x217c3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2057c28 sp=0x2057b9c pc=0x47b1ec Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).Reader(...) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).Read(0x217c3c8, {0x8874d0, 0xd0ad00}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2057c50 sp=0x2057c28 pc=0x4798bc Jan 12 21:23:23 volumio go-librespot[6635]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2057ccc sp=0x2057c50 pc=0x633988 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.HandlerFunc.ServeHTTP(0x2030190, {0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2057cdc sp=0x2057ccc pc=0x33d7f4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*ServeMux).ServeHTTP(0x2076000, {0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2057d18 sp=0x2057cdc pc=0x33f654 Jan 12 21:23:23 volumio go-librespot[6635]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2057d40 sp=0x2057d18 pc=0x633388 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.HandlerFunc.ServeHTTP(0x200e190, {0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2057d50 sp=0x2057d40 pc=0x33d7f4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.serverHandler.ServeHTTP({0x207a0a8}, {0x886dec, 0x207ac88}, 0x207abe8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2057d6c sp=0x2057d50 pc=0x340de4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*conn).serve(0x2029c20, {0x887510, 0x2026198}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2057fdc sp=0x2057d6c pc=0x33c034 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*Server).Serve.gowrap3() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2057fec sp=0x2057fdc pc=0x341688 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2057fec sp=0x2057fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by net/http.(*Server).Serve in goroutine 23 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 9 gp=0x21a6368 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061e68, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21aead4 sp=0x21aeac0 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061e58, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x21aeaec sp=0x21aead4 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061e58, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x21aeb00 sp=0x21aeaec pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x220a068, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21aeb14 sp=0x21aeb00 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x220a050, {0x2117000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x21aeb5c sp=0x21aeb14 pc=0x10b504 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).Read(0x220a050, {0x2117000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x21aeb88 sp=0x21aeb5c pc=0x17c5d0 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*conn).Read(0x20918e8, {0x2117000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x21aebb4 sp=0x21aeb88 pc=0x18caec Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPConn).Read(0x20918e8, {0x2117000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x44 fp=0x21aebd4 sp=0x21aebb4 pc=0x19fd40 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*atLeastReader).Read(0x2012040, {0x2117000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x21aec00 sp=0x21aebd4 pc=0x27c680 Jan 12 21:23:23 volumio go-librespot[6635]: bytes.(*Buffer).ReadFrom(0x20cbecc, {0x883674, 0x2012040}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x21aec3c sp=0x21aec00 pc=0x139bd0 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readFromUntil(0x20cbd48, {0x882848, 0x20918e8}, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x21aec64 sp=0x21aec3c pc=0x27c8dc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecordOrCCS(0x20cbd48, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x21aedc8 sp=0x21aec64 pc=0x279fdc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).Read(0x20cbd48, {0x2275000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x21aedf8 sp=0x21aedc8 pc=0x27fe4c Jan 12 21:23:23 volumio go-librespot[6635]: bufio.(*Reader).Read(0x2272270, {0x20ce824, 0x9, 0x9}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x21aee1c sp=0x21aedf8 pc=0x2be440 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadAtLeast({0x882a3c, 0x2272270}, {0x20ce824, 0x9, 0x9}, 0x9) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21aee48 sp=0x21aee1c pc=0x1042c0 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadFull(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.http2readFrameHeader({0x20ce824, 0x9, 0x9}, {0x882a3c, 0x2272270}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x21aee70 sp=0x21aee48 pc=0x2f733c Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2Framer).ReadFrame(0x20ce800) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x21aeeec sp=0x21aee70 pc=0x2f7ad4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2clientConnReadLoop).run(0x21aefdc) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x21aefa4 sp=0x21aeeec pc=0x31c2f0 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2ClientConn).readLoop(0x20f9d08) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x21aefe4 sp=0x21aefa4 pc=0x31b7e4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x21aefec sp=0x21aefe4 pc=0x314830 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21aefec sp=0x21aefec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by net/http.(*http2Transport).newClientConn in goroutine 8 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 34 gp=0x24ae008 m=nil [GC worker (idle)]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3fbc, 0x235f068, 0x1a, 0xa, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b4790 sp=0x24b477c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcBgMarkWorker() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x24b47ec sp=0x24b4790 pc=0x38590 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b47ec sp=0x24b47ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 5 gp=0x2002d88 m=nil [GC worker (idle)]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3fbc, 0x202a078, 0x1a, 0xa, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2045790 sp=0x204577c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcBgMarkWorker() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20457ec sp=0x2045790 pc=0x38590 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20457ec sp=0x20457ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 6 gp=0x2002ea8 m=nil [GC worker (idle)]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3fbc, 0x202a090, 0x1a, 0xa, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2045f90 sp=0x2045f7c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcBgMarkWorker() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2045fec sp=0x2045f90 pc=0x38590 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2045fec sp=0x2045fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 7 gp=0x2002fc8 m=nil [GC worker (idle)]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3fbc, 0x202a0a8, 0x1a, 0xa, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2046790 sp=0x204677c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gcBgMarkWorker() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20467ec sp=0x2046790 pc=0x38590 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20467ec sp=0x20467ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by runtime.gcBgMarkStartWorkers in goroutine 27 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 79 gp=0x24ae488 m=nil [select]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20426a8 sp=0x2042694 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.selectgo(0x20427a4, 0x204276c, 0x0, 0x0, 0x5, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2042748 sp=0x20426a8 pc=0x70e78 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x24e6148) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x20427e4 sp=0x2042748 pc=0x474738 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.newConn.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x20427ec sp=0x20427e4 pc=0x47432c Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by nhooyr.io/websocket.newConn in goroutine 33 Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 37 gp=0x24ae5a8 m=nil [chan receive]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3f90, 0x20a0230, 0xe, 0x7, 0x2) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b3f48 sp=0x24b3f34 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.chanrecv(0x20a0200, 0x24b3fd0, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x24b3f84 sp=0x24b3f48 pc=0x20078 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.chanrecv2(0x20a0200, 0x24b3fd0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x24b3f98 sp=0x24b3f84 pc=0x1fbbc Jan 12 21:23:23 volumio go-librespot[6635]: main.(*App).withAppPlayer.func1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:268 +0x68 fp=0x24b3fec sp=0x24b3f98 pc=0x63e794 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b3fec sp=0x24b3fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by main.(*App).withAppPlayer in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:265 +0x464 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 38 gp=0x24ae6c8 m=nil [chan receive]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e3f90, 0x20a01f0, 0xe, 0x7, 0x2) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b4f40 sp=0x24b4f2c pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.chanrecv(0x20a01c0, 0x24b4fe0, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x24b4f7c sp=0x24b4f40 pc=0x20078 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.chanrecv2(0x20a01c0, 0x24b4fe0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x24b4f90 sp=0x24b4f7c pc=0x1fbbc Jan 12 21:23:23 volumio go-librespot[6635]: main.(*App).withAppPlayer.func2() Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:284 +0x6c fp=0x24b4fec sp=0x24b4f90 pc=0x63e330 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b4fec sp=0x24b4fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by main.(*App).withAppPlayer in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /src/cmd/daemon/main.go:281 +0x504 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 39 gp=0x24ae7e8 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061dc8, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jan 12 21:23:23 volumio volumio[1250]: info: Connection to go-librespot Websocket closed Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b55e8 sp=0x24b55d4 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061db8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x24b5600 sp=0x24b55e8 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061db8, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x24b5614 sp=0x24b5600 pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x2286018, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24b5628 sp=0x24b5614 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Accept(0x2286000) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x24b5670 sp=0x24b5628 pc=0x10eb84 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).accept(0x2286000) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x24b56d8 sp=0x24b5670 pc=0x17e4c4 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPListener).accept(0x20160a8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x24b56f0 sp=0x24b56d8 pc=0x196900 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPListener).Accept(0x20160a8) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x24b570c sp=0x24b56f0 pc=0x19596c Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*onceCloseListener).Accept(0x219a1a0) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x34 fp=0x24b5724 sp=0x24b570c pc=0x362b54 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*Server).Serve(0x20b8328, {0x886cb8, 0x20160a8}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x24b57bc sp=0x24b5724 pc=0x341210 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.Serve(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/server.go:2794 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jan 12 21:23:23 volumio go-librespot[6635]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x24b57ec sp=0x24b57bc pc=0x5a8184 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b57ec sp=0x24b57ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jan 12 21:23:23 volumio go-librespot[6635]: /src/zeroconf/zeroconf.go:280 +0x160 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 40 gp=0x24ae908 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061d28, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2054e84 sp=0x2054e70 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061d18, 0x72, 0x0) Jan 12 21:23:23 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2054e9c sp=0x2054e84 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061d18, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2054eb0 sp=0x2054e9c pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x2286068, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2054ec4 sp=0x2054eb0 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).RawRead(0x2286050, 0x2318f90) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2054ef0 sp=0x2054ec4 pc=0x10f788 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*rawConn).Read(0x230e090, 0x2318f90) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2054f10 sp=0x2054ef0 pc=0x190d28 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2012120, 0x2318f60, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2054f40 sp=0x2054f10 pc=0x548824 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x228a038, {0x236a000, 0x10000, 0x10000}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x2054f9c sp=0x2054f40 pc=0x54c80c Jan 12 21:23:23 volumio go-librespot[6635]: github.com/grandcat/zeroconf.(*Server).recv4(0x2094800, 0x228a030) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x2054fe0 sp=0x2054f9c pc=0x5a309c Jan 12 21:23:23 volumio go-librespot[6635]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x2054fec sp=0x2054fe0 pc=0x5a2d54 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2054fec sp=0x2054fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 35 Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 41 gp=0x24aea28 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061c88, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b665c sp=0x24b6648 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061c78, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x24b6674 sp=0x24b665c pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061c78, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x24b6688 sp=0x24b6674 pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x22860b8, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24b669c sp=0x24b6688 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).RawRead(0x22860a0, 0x228a120) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x24b66c8 sp=0x24b669c pc=0x10f788 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*rawConn).Read(0x230e0b8, 0x228a120) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x24b66e8 sp=0x24b66c8 pc=0x190d28 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2012130, 0x228a0f0, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x24b6718 sp=0x24b66e8 pc=0x548824 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jan 12 21:23:23 volumio go-librespot[6635]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x228a068, {0x229a000, 0x10000, 0x10000}) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x24b679c sp=0x24b6718 pc=0x550194 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/grandcat/zeroconf.(*Server).recv6(0x2094800, 0x228a060) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x24b67e0 sp=0x24b679c pc=0x5a3290 Jan 12 21:23:23 volumio go-librespot[6635]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x24b67ec sp=0x24b67e0 pc=0x5a2cf8 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b67ec sp=0x24b67ec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 35 Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 13 gp=0x21a6908 m=nil [select]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b7ea8 sp=0x24b7e94 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.selectgo(0x24b7fa4, 0x24b7f6c, 0x0, 0x0, 0x5, 0x1) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24b7f48 sp=0x24b7ea8 pc=0x70e78 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x217c3c8) Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x24b7fe4 sp=0x24b7f48 pc=0x474738 Jan 12 21:23:23 volumio go-librespot[6635]: nhooyr.io/websocket.newConn.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x24b7fec sp=0x24b7fe4 pc=0x47432c Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b7fec sp=0x24b7fec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by nhooyr.io/websocket.newConn in goroutine 11 Jan 12 21:23:23 volumio go-librespot[6635]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 66 gp=0x24af568 m=nil [IO wait]: Jan 12 21:23:23 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061aa8, 0x2, 0x2, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21abad4 sp=0x21abac0 pc=0x5d0c0 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.netpollblock(0xb6061a98, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x21abaec sp=0x21abad4 pc=0x548cc Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061a98, 0x72) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x21abb00 sp=0x21abaec pc=0x8f4a8 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x2287d28, 0x72, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21abb14 sp=0x21abb00 pc=0x10a318 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x2287d10, {0x21c8000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x21abb5c sp=0x21abb14 pc=0x10b504 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).Read(0x2287d10, {0x21c8000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x21abb88 sp=0x21abb5c pc=0x17c5d0 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*conn).Read(0x2090bc0, {0x21c8000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x21abbb4 sp=0x21abb88 pc=0x18caec Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPConn).Read(0x2090bc0, {0x21c8000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x44 fp=0x21abbd4 sp=0x21abbb4 pc=0x19fd40 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*atLeastReader).Read(0x2012b00, {0x21c8000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x21abc00 sp=0x21abbd4 pc=0x27c680 Jan 12 21:23:23 volumio go-librespot[6635]: bytes.(*Buffer).ReadFrom(0x21e1c8c, {0x883674, 0x2012b00}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x21abc3c sp=0x21abc00 pc=0x139bd0 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readFromUntil(0x21e1b08, {0x882848, 0x2090bc0}, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x21abc64 sp=0x21abc3c pc=0x27c8dc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecordOrCCS(0x21e1b08, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x21abdc8 sp=0x21abc64 pc=0x279fdc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).Read(0x21e1b08, {0x21d6000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x21abdf8 sp=0x21abdc8 pc=0x27fe4c Jan 12 21:23:23 volumio go-librespot[6635]: bufio.(*Reader).Read(0x2273b30, {0x20ce924, 0x9, 0x9}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x21abe1c sp=0x21abdf8 pc=0x2be440 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadAtLeast({0x882a3c, 0x2273b30}, {0x20ce924, 0x9, 0x9}, 0x9) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21abe48 sp=0x21abe1c pc=0x1042c0 Jan 12 21:23:23 volumio go-librespot[6635]: io.ReadFull(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.http2readFrameHeader({0x20ce924, 0x9, 0x9}, {0x882a3c, 0x2273b30}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x21abe70 sp=0x21abe48 pc=0x2f733c Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2Framer).ReadFrame(0x20ce900) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x21abeec sp=0x21abe70 pc=0x2f7ad4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2clientConnReadLoop).run(0x21abfdc) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x21abfa4 sp=0x21abeec pc=0x31c2f0 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2ClientConn).readLoop(0x20f9f08) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x21abfe4 sp=0x21abfa4 pc=0x31b7e4 Jan 12 21:23:23 volumio go-librespot[6635]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x21abfec sp=0x21abfe4 pc=0x314830 Jan 12 21:23:23 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21abfec sp=0x21abfec pc=0x93d6c Jan 12 21:23:23 volumio go-librespot[6635]: created by net/http.(*http2Transport).newClientConn in goroutine 16 Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 12 21:23:23 volumio go-librespot[6635]: goroutine 57 gp=0x21a6b48 m=nil [runnable]: Jan 12 21:23:23 volumio go-librespot[6635]: syscall.Syscall(0x3, 0xd, 0x21bc000, 0x1000) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/syscall/syscall_linux.go:69 +0x8 fp=0x21adaec sp=0x21adab4 pc=0xa8554 Jan 12 21:23:23 volumio go-librespot[6635]: syscall.read(0xd, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/syscall/zsyscall_linux_arm.go:736 +0x48 fp=0x21adb14 sp=0x21adaec pc=0xa63d8 Jan 12 21:23:23 volumio go-librespot[6635]: syscall.Read(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/syscall/syscall_unix.go:181 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.ignoringEINTRIO(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:736 Jan 12 21:23:23 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x241a0a0, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:160 +0x270 fp=0x21adb5c sp=0x21adb14 pc=0x10b53c Jan 12 21:23:23 volumio go-librespot[6635]: net.(*netFD).Read(0x241a0a0, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x21adb88 sp=0x21adb5c pc=0x17c5d0 Jan 12 21:23:23 volumio go-librespot[6635]: net.(*conn).Read(0x23e0488, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x21adbb4 sp=0x21adb88 pc=0x18caec Jan 12 21:23:23 volumio go-librespot[6635]: net.(*TCPConn).Read(0x23e0488, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: :1 +0x44 fp=0x21adbd4 sp=0x21adbb4 pc=0x19fd40 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*atLeastReader).Read(0x200e020, {0x21bc000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x21adc00 sp=0x21adbd4 pc=0x27c680 Jan 12 21:23:23 volumio go-librespot[6635]: bytes.(*Buffer).ReadFrom(0x22cef0c, {0x883674, 0x200e020}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x21adc3c sp=0x21adc00 pc=0x139bd0 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readFromUntil(0x22ced88, {0x882848, 0x23e0488}, 0x5) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x21adc64 sp=0x21adc3c pc=0x27c8dc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecordOrCCS(0x22ced88, 0x0) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x21addc8 sp=0x21adc64 pc=0x279fdc Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:23 volumio go-librespot[6635]: crypto/tls.(*Conn).Read(0x22ced88, {0x21de000, 0x1000, 0x1000}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x21addf8 sp=0x21addc8 pc=0x27fe4c Jan 12 21:23:23 volumio go-librespot[6635]: bufio.(*Reader).Read(0x23d9800, {0x2076124, 0x9, 0x9}) Jan 12 21:23:23 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x21ade1c sp=0x21addf8 pc=0x2be440 Jan 12 21:23:24 volumio go-librespot[6635]: io.ReadAtLeast({0x882a3c, 0x23d9800}, {0x2076124, 0x9, 0x9}, 0x9) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21ade48 sp=0x21ade1c pc=0x1042c0 Jan 12 21:23:24 volumio go-librespot[6635]: io.ReadFull(...) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.http2readFrameHeader({0x2076124, 0x9, 0x9}, {0x882a3c, 0x23d9800}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x21ade70 sp=0x21ade48 pc=0x2f733c Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2Framer).ReadFrame(0x2076100) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x21adeec sp=0x21ade70 pc=0x2f7ad4 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2clientConnReadLoop).run(0x21adfdc) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x21adfa4 sp=0x21adeec pc=0x31c2f0 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2ClientConn).readLoop(0x249a108) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x21adfe4 sp=0x21adfa4 pc=0x31b7e4 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x21adfec sp=0x21adfe4 pc=0x314830 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21adfec sp=0x21adfec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by net/http.(*http2Transport).newClientConn in goroutine 56 Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 12 21:23:24 volumio go-librespot[6635]: goroutine 32 gp=0x20030e8 m=nil [select]: Jan 12 21:23:24 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b5e74 sp=0x24b5e60 pc=0x5d0c0 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.selectgo(0x24b5f74, 0x24b5f3c, 0x0, 0x0, 0x3, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24b5f14 sp=0x24b5e74 pc=0x70e78 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x23faf00) Jan 12 21:23:24 volumio go-librespot[6635]: /src/player/player.go:201 +0x1fc fp=0x24b5fe4 sp=0x24b5f14 pc=0x513660 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jan 12 21:23:24 volumio go-librespot[6635]: /src/player/player.go:183 +0x28 fp=0x24b5fec sp=0x24b5fe4 pc=0x513240 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b5fec sp=0x24b5fec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jan 12 21:23:24 volumio go-librespot[6635]: /src/player/player.go:183 +0x218 Jan 12 21:23:24 volumio go-librespot[6635]: goroutine 30 gp=0x20858c8 m=nil [IO wait]: Jan 12 21:23:24 volumio go-librespot[6635]: runtime.gopark(0x7e40f0, 0xb6061968, 0x2, 0x2, 0x5) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x21aaad4 sp=0x21aaac0 pc=0x5d0c0 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.netpollblock(0xb6061958, 0x72, 0x0) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x21aaaec sp=0x21aaad4 pc=0x548cc Jan 12 21:23:24 volumio go-librespot[6635]: internal/poll.runtime_pollWait(0xb6061958, 0x72) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x21aab00 sp=0x21aaaec pc=0x8f4a8 Jan 12 21:23:24 volumio go-librespot[6635]: internal/poll.(*pollDesc).wait(0x241a158, 0x72, 0x0) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x21aab14 sp=0x21aab00 pc=0x10a318 Jan 12 21:23:24 volumio go-librespot[6635]: internal/poll.(*pollDesc).waitRead(...) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jan 12 21:23:24 volumio go-librespot[6635]: internal/poll.(*FD).Read(0x241a140, {0x23f2000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x21aab5c sp=0x21aab14 pc=0x10b504 Jan 12 21:23:24 volumio go-librespot[6635]: net.(*netFD).Read(0x241a140, {0x23f2000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x21aab88 sp=0x21aab5c pc=0x17c5d0 Jan 12 21:23:24 volumio go-librespot[6635]: net.(*conn).Read(0x2090f70, {0x23f2000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x21aabb4 sp=0x21aab88 pc=0x18caec Jan 12 21:23:24 volumio go-librespot[6635]: net.(*TCPConn).Read(0x2090f70, {0x23f2000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: :1 +0x44 fp=0x21aabd4 sp=0x21aabb4 pc=0x19fd40 Jan 12 21:23:24 volumio go-librespot[6635]: crypto/tls.(*atLeastReader).Read(0x23de2a0, {0x23f2000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x21aac00 sp=0x21aabd4 pc=0x27c680 Jan 12 21:23:24 volumio go-librespot[6635]: bytes.(*Buffer).ReadFrom(0x231a18c, {0x883674, 0x23de2a0}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x21aac3c sp=0x21aac00 pc=0x139bd0 Jan 12 21:23:24 volumio go-librespot[6635]: crypto/tls.(*Conn).readFromUntil(0x231a008, {0x882848, 0x2090f70}, 0x5) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x21aac64 sp=0x21aac3c pc=0x27c8dc Jan 12 21:23:24 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecordOrCCS(0x231a008, 0x0) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x21aadc8 sp=0x21aac64 pc=0x279fdc Jan 12 21:23:24 volumio go-librespot[6635]: crypto/tls.(*Conn).readRecord(...) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:588 Jan 12 21:23:24 volumio go-librespot[6635]: crypto/tls.(*Conn).Read(0x231a008, {0x249c000, 0x1000, 0x1000}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x21aadf8 sp=0x21aadc8 pc=0x27fe4c Jan 12 21:23:24 volumio go-librespot[6635]: bufio.(*Reader).Read(0x23d8e70, {0x24a0024, 0x9, 0x9}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x21aae1c sp=0x21aadf8 pc=0x2be440 Jan 12 21:23:24 volumio go-librespot[6635]: io.ReadAtLeast({0x882a3c, 0x23d8e70}, {0x24a0024, 0x9, 0x9}, 0x9) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x21aae48 sp=0x21aae1c pc=0x1042c0 Jan 12 21:23:24 volumio go-librespot[6635]: io.ReadFull(...) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/io/io.go:354 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.http2readFrameHeader({0x24a0024, 0x9, 0x9}, {0x882a3c, 0x23d8e70}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x21aae70 sp=0x21aae48 pc=0x2f733c Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2Framer).ReadFrame(0x24a0000) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x21aaeec sp=0x21aae70 pc=0x2f7ad4 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2clientConnReadLoop).run(0x21aafdc) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x21aafa4 sp=0x21aaeec pc=0x31c2f0 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2ClientConn).readLoop(0x249a008) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x21aafe4 sp=0x21aafa4 pc=0x31b7e4 Jan 12 21:23:24 volumio go-librespot[6635]: net/http.(*http2Transport).newClientConn.gowrap1() Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x21aafec sp=0x21aafe4 pc=0x314830 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x21aafec sp=0x21aafec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by net/http.(*http2Transport).newClientConn in goroutine 29 Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Jan 12 21:23:24 volumio go-librespot[6635]: goroutine 81 gp=0x24ee488 m=nil [select]: Jan 12 21:23:24 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b6eb8 sp=0x24b6ea4 pc=0x5d0c0 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.selectgo(0x24b6fd4, 0x24b6f7c, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24b6f58 sp=0x24b6eb8 pc=0x70e78 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x21018c8) Jan 12 21:23:24 volumio go-librespot[6635]: /src/ap/ap.go:355 +0x8c fp=0x24b6fe4 sp=0x24b6f58 pc=0x48cb50 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jan 12 21:23:24 volumio go-librespot[6635]: /src/ap/ap.go:264 +0x28 fp=0x24b6fec sp=0x24b6fe4 pc=0x48c248 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b6fec sp=0x24b6fec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 33 Jan 12 21:23:24 volumio go-librespot[6635]: /src/ap/ap.go:264 +0x15c Jan 12 21:23:24 volumio go-librespot[6635]: goroutine 98 gp=0x24ee5a8 m=nil [chan receive]: Jan 12 21:23:24 volumio go-librespot[6635]: runtime.gopark(0x7e3f90, 0x21d40b0, 0xe, 0x7, 0x2) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2035d60 sp=0x2035d4c pc=0x5d0c0 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.chanrecv(0x21d4080, 0x2035ddb, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2035d9c sp=0x2035d60 pc=0x20078 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.chanrecv1(0x21d4080, 0x2035ddb) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x2035db0 sp=0x2035d9c pc=0x1fb98 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleRequest(0x24a22c8, 0x2359130) Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/recv.go:237 +0x1f8 fp=0x2035f2c sp=0x2035db0 pc=0x533ab4 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x24a22c8) Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:213 +0x4d8 fp=0x2035fe4 sp=0x2035f2c pc=0x5321e4 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:125 +0x28 fp=0x2035fec sp=0x2035fe4 pc=0x53383c Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2035fec sp=0x2035fec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 33 Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:125 +0x98 Jan 12 21:23:24 volumio go-librespot[6635]: goroutine 99 gp=0x24ee6c8 m=nil [select]: Jan 12 21:23:24 volumio go-librespot[6635]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b3698 sp=0x24b3684 pc=0x5d0c0 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.selectgo(0x24b37d4, 0x24b3760, 0x0, 0x0, 0x2, 0x1) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24b3738 sp=0x24b3698 pc=0x70e78 Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x24a22c8) Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:138 +0x8c fp=0x24b37e4 sp=0x24b3738 pc=0x5318dc Jan 12 21:23:24 volumio go-librespot[6635]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:129 +0x28 fp=0x24b37ec sp=0x24b37e4 pc=0x5337e8 Jan 12 21:23:24 volumio go-librespot[6635]: runtime.goexit({}) Jan 12 21:23:24 volumio go-librespot[6635]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b37ec sp=0x24b37ec pc=0x93d6c Jan 12 21:23:24 volumio go-librespot[6635]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 33 Jan 12 21:23:24 volumio go-librespot[6635]: /src/dealer/dealer.go:129 +0x15c Jan 12 21:23:24 volumio go-librespot[6634]: Aborted Jan 12 21:23:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:24 volumio volumio[1250]: info: CoreCommandRouter::volumioGetState Jan 12 21:23:24 volumio volumio[1250]: info: Getting Spotify volume Jan 12 21:23:24 volumio volumio[1250]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 21:23:24 volumio volumio[1250]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 12 21:23:24 volumio volumio[1250]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 12 21:23:24 volumio volumio[1250]: errno: -111, Jan 12 21:23:24 volumio volumio[1250]: code: 'ECONNREFUSED', Jan 12 21:23:24 volumio volumio[1250]: syscall: 'connect', Jan 12 21:23:24 volumio volumio[1250]: address: '127.0.0.1', Jan 12 21:23:24 volumio volumio[1250]: port: 9879, Jan 12 21:23:24 volumio volumio[1250]: response: undefined Jan 12 21:23:24 volumio volumio[1250]: } Jan 12 21:23:24 volumio volumio[1250]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 12 21:23:25 volumio sudo[6671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-12 21:22' Jan 12 21:23:25 volumio sudo[6671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"