search

sle118 / squeezelite-esp32

ESP32 Music streaming based on Squeezelite, with support for multi-room sync, AirPlay, Bluetooth, Hardware buttons, display and more
1.19k stars 108 forks source link

Audio kit failing after a few minutes #200

Closed FHFS closed 1 year ago

FHFS commented 1 year ago

Hi everyone,

Thanks for this amazing project, I'm really enjoying LMS and Squeezelite.

I've bought two ESP32 Audio Kit v2.2 dev boards. one with an A149 and another with an A210 version number. Both with a ESP32-A1S 2974

The A210 is working perfectly, but the A149 is jittering audio or not playing at all after a few minutes. The web interface is also not responding at that time. I've dumped the flash from the A210 and flashed it to the A149 to make sure there are no differences between them. I've also switched the environments for the boards, with the same results. The device does not reset or crash.

I am guessing my A149 is broken, but maybe it is a PCB revision issue or smthn? Any ideas?

Logs

--- idf_monitor on /dev/ttyUSB0 115200 --- --- Quit: Ctrl+] | Menu: Ctrl+T | Help: Ctrl+T followed by Ctrl+H --- ets Jun 8 2016 00:22:57 rst:0x1 (POWERON_RESET),boot:0x1f (SPI_FAST_FLASH_BOOT) configsip: 0, SPIWP:0xee clk_drv:0x00,q_drv:0x00,d_drv:0x00,cs0_drv:0x00,hd_drv:0x00,wp_drv:0x00 mode:DIO, clock div:1 load:0x3fff0018,len:4 load:0x3fff001c,len:7004 load:0x40078000,len:14716 ho 0 tail 12 room 4 load:0x40080400,len:4748 entry 0x400806a0 I (63) boot: Chip Revision: 1 I (69) boot_comm: chip revision: 1, min. bootloader chip revision: 0 I (41) boot: ESP-IDF v4.0-1-g526475efb2 2nd stage bootloader I (41) boot: compile time 21:38:53 I (41) boot: Enabling RNG early entropy source... I (47) qio_mode: Enabling default flash chip QIO I (52) boot: SPI Speed : 80MHz I (56) boot: SPI Mode : QIO I (60) boot: SPI Flash Size : 4MB I (64) boot: Partition Table: I (68) boot: ## Label Usage Type ST Offset Length I (75) boot: 0 nvs WiFi data 01 02 00009000 00004000 I (82) boot: 1 otadata OTA data 01 00 0000d000 00002000 I (90) boot: 2 phy_init RF data 01 01 0000f000 00001000 I (97) boot: 3 recovery factory app 00 00 00010000 00140000 I (105) boot: 4 ota_0 OTA app 00 10 00150000 002a0000 I (112) boot: 5 settings WiFi data 01 02 003f0000 00010000 I (120) boot: End of partition table I (124) boot_comm: chip revision: 1, min. application chip revision: 0 I (131) esp_image: segment 0: paddr=0x00150020 vaddr=0x3f400020 size=0xce8f0 (846064) map I (414) esp_image: segment 1: paddr=0x0021e918 vaddr=0x3ffbdb60 size=0x016f8 ( 5880) load I (416) esp_image: segment 2: paddr=0x00220018 vaddr=0x400d0018 size=0x1737d4 (1521620) map 0x400d0018: _stext at ??:? I (911) esp_image: segment 3: paddr=0x003937f4 vaddr=0x3ffbf258 size=0x04acc ( 19148) load I (918) esp_image: segment 4: paddr=0x003982c8 vaddr=0x40080000 size=0x00400 ( 1024) load 0x40080000: _WindowOverflow4 at /home/fhfs/Repos/esp-idf/components/freertos/xtensa_vectors.S:1778 I (919) esp_image: segment 5: paddr=0x003986d0 vaddr=0x40080400 size=0x1d268 (119400) load I (971) esp_image: segment 6: paddr=0x003b5940 vaddr=0x400c0000 size=0x0006c ( 108) load I (992) boot: Loaded app from partition at offset 0x150000 I (992) boot: Disabling RNG early entropy source... I (992) psram: This chip is ESP32-D0WD I (997) spiram: Found 64MBit SPI RAM device I (1001) spiram: SPI RAM mode: flash 80m sram 80m I (1007) spiram: PSRAM initialized, cache is in low/high (2-core) mode. I (1014) cpu_start: Pro cpu up. I (1018) cpu_start: Application information: I (1023) cpu_start: Project name: Squeezelite-ESP32 I (1029) cpu_start: App version: local.500.cmake-master I (1035) cpu_start: Compile time: Aug 16 2022 21:39:29 I (1041) cpu_start: ELF file SHA256: 2e4f04d3f3fde6df... I (1047) cpu_start: ESP-IDF: v4.0-1-g526475efb2 I (1053) cpu_start: Starting app cpu, entry point is 0x40081790 0x40081790: call_start_cpu0 at /home/fhfs/Repos/esp-idf/components/esp32/cpu_start.c:196 (discriminator 1) I (0) cpu_start: App cpu up. I (1550) spiram: SPI SRAM memory test OK I (1554) heap_init: Initializing. RAM available for dynamic allocation: I (1554) heap_init: At 3FFAFF10 len 000000F0 (0 KiB): DRAM I (1557) heap_init: At 3FFB6388 len 00001C78 (7 KiB): DRAM I (1563) heap_init: At 3FFB9A20 len 00004108 (16 KiB): DRAM I (1569) heap_init: At 3FFBDB5C len 00000004 (0 KiB): DRAM I (1575) heap_init: At 3FFC6DD0 len 00019230 (100 KiB): DRAM I (1582) heap_init: At 3FFE0440 len 00003AE0 (14 KiB): D/IRAM I (1588) heap_init: At 3FFE4350 len 0001BCB0 (111 KiB): D/IRAM I (1595) heap_init: At 4009D668 len 00002998 (10 KiB): IRAM I (1601) cpu_start: Pro cpu start user code I (1606) spiram: Adding pool of 4033K of external SPI memory to heap allocator I (1626) spi_flash: detected chip: generic I (1627) spi_flash: flash io: qio I (1627) esp_core_dump_common: Init core dump to UART E (1636) esp_core_dump_common: No core dump partition found! I (1637) cpu_start: Starting scheduler on PRO CPU. I (0) cpu_start: Starting scheduler on APP CPU. I (1647) spiram: Reserving pool of 64K of internal memory for DMA/internal allocations I (1657) esp_app_main: Reset reason is: 1 I (1657) esp_app_main: Starting app_main I (1667) nvs_utilities: Initializing flash nvs I (1697) nvs_utilities: Initializing nvs partition settings I (1727) esp_app_main: Setting up telnet. I (1727) telnet: Telnet support disabled I (1727) esp_app_main: Setting up config subsystem. I (1777) esp_app_main: Registering default values I (1777) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE W (1777) config: Waiting for config commit ... I (2777) config: configuration has some uncommitted entries I (2777) config: Committing configuration to nvs. Locking config object. I (2777) config: Config committed! I (2777) esp_app_main: Configuring services I (2787) config: Done Committing configuration to nvs. I (2787) services: Configuring I2C sda:-1 scl:-1 port:1 speed:400000 W (2797) services: no I2C configured I (2797) services: Configuring SPI mosi:-1 miso:-1 clk:-1 host:1 dc:-1 W (2807) services: no SPI configured I (2817) led: Configuring LEDs green:22 (active:1 -1%), red:19 (active:1 -1%) I (2817) battery: No battery I (2827) monitor: Initializing monitoring I (2827) monitor: Adding jack (low) detection GPIO 39 I (2837) buttons: Creating button using GPIO 39, type 0, pull-up/down 0, long press 0 shifter -1 I (2847) buttons: starting button tasks W (2847) buttons: creating polled gpio 39, level 1 I (2857) monitor: Heap internal:238996 (min:222676) external:4097944 (min:4097944) I (2857) esp_app_main: Initializing display I (2867) display: Trying to configure display with N/A W (2877) display: No display driver I (2877) esp_app_main: Checking if certificates need to be updated I (2887) esp_app_main: About to check if certificates need to be updated in flash I (2887) esp_app_main: Running partition [ota_0] type 0 subtype 16 (offset 0x00150000) I (2897) esp_app_main: Running version: local.500.cmake-master I (2907) esp_app_main: Certificate version: local.500.cmake-master I (2917) esp_app_main: Checking for update url W (2917) config: Value not found for key fwurl W (2927) config: Value not found for key I (2927) esp_app_main: Starting Wifi Manager I (2947) wifi: wifi driver task: 3ffdb388, prio:23, stack:3584, core=0 I (2947) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE I (2947) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE I (2967) wifi: wifi firmware version: 581f422 I (2967) wifi: config NVS flash: enabled I (2967) wifi: config nano formating: disabled I (2977) wifi: Init dynamic tx buffer num: 32 I (2977) wifi: Init data frame dynamic rx buffer num: 40 I (2977) wifi: Init management frame dynamic rx buffer num: 40 I (2987) wifi: Init management short buffer num: 32 I (2997) wifi: Init static tx buffer num: 12 I (2997) wifi: Init static rx buffer size: 1600 I (2997) wifi: Init static rx buffer num: 12 I (3007) wifi: Init dynamic rx buffer num: 40 I (3087I phy: phy_version: 4180, cb3948e, Sep 12 2019, 16:39:13, 0, 0 I (3087) wifi: mode : null I (3097) http_server: Initializing HTTP Server I (3097) uart: queue free spaces: 3 I (3107) wifi_manager: Saved wifi found on startup. Will attempt to connect. I (3107) wifi: mode : sta (a4:e5:7c:e8:71:e0) I (3117) wifi_manager: Wifi Connecting... Type 'help' to get the list of commands. Use UP/DOWN arrows to navigate through command history. Press TAB when typing command name to auto-complete. To automatically execute lines at startup: Set NVS variable autoexec (U8) = 1 to enable, 0 to disable automatic execution. Set NVS variable autoexec[1~9] (string)to a command that should be executed automatically **************************** Your terminal application does not support escape sequences. Line editing and history features are disabled. On Windows, try using Putty instead. **************************** W (3197) console: Processing autoexec commands while wifi_manager active. Wifi related commands will be ignored. I (3207) console: autoexec is set to perform auto-process I (3207) console: Running command autoexec1 = squeezelite -b 500:2000 -d all=info -C 30 -W -o I2S -n "squeezelite-kantoor" W (3217) config: Value not found for key autoexec2 I (3217) squeezelite_cmd: Calling squeezelite I (3227) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE squeezelite-esp32> [00:00:01.623380] stream_init:387 init stream [00:00:01.624882] sb_controls_init:244 initializing audio (buttons/rotary/ir) controls (raw:0) I (3257) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE [00:00:01.647713] sb_displayer_init:312 no display or led visualizer for LMS [00:00:01.648674] output_init_embedded:70 init device: I2S [00:00:01.660522] equalizer_init:42 initializing equalizer [00:00:01.661927] output_init_common:433 supported rates: 192000 176400 96000 88200 48000 44100 32000 24000 22050 16000 12000 11025 8000 [00:00:01.673736] output_init_embedded:92 init I2S/SPDIF I (3297) DAC core: DAC uses I2C port:0, sda:33, scl:32 I (3307) DAC external: DAC on I2C @16 I (3307) DAC external: Configuring MCLK on GPIO0 I (3327) I2S: DMA Malloc info, datalen=blocksize=2048, dma_buf_count=12 I (3327) I2S: APLL: Req RATE: 44100, real rate: 44099.988, BITS: 16, CLKM: 1, BCK_M: 8, MCLK: 11289597.000, SCLK: 1411199.625000, diva: 1, divb: 0 [00:00:01.717134] output_init_i2s:321 ES8388 DAC using I2S bck:27, ws:25, do:26, mute:-1:0 (res:0) [00:00:01.728680] output_init_i2s:341 Initializing I2S mode normal with rate: 44100, bits per sample: 16, buffer frames: 512, number of buffers: 12 [00:00:01.740960] output_init_i2s:360 setting amplifier GPIO 21 (active:1) [00:00:01.741938] output_init_i2s:367 setting external amplifier GPIO 5 (active:0) [00:00:01.753061] output_init_i2s:374 setting inverted external amplifier GPIO 23 (active:1) [00:00:01.764406] output_thread_i2s:480 Output state is -1 [00:00:01.764664] output_visu_init:75 Initialize VISUEXPORT 512 16 bits samples [00:00:01.765634] output_thread_i2s:484 switching off amp GPIO 21 [00:00:01.776688] output_init_embedded:100 init completed. [00:00:01.777442] output_thread_i2s:486 switching off ext amp GPIO 5 [00:00:01.788333] decode_init:153 init decode [00:00:01.789100] output_thread_i2s:488 switching on inverted ext amp GPIO 23 [00:00:01.799807] register_alac:547 using alac to decode alc [00:00:01.811484] register_helixaac:686 using helix-aac to decode aac [00:00:01.812417] register_vorbis:385 using vorbis to decode ogg [00:00:01.823375] register_opus:328 using opus to decode ops [00:00:01.824149] register_flac:332 using flac to decode ogf,flc [00:00:01.835100] register_pcm:468 using pcm to decode wav,aif,pcm [00:00:01.835934] register_mad:417 using mad to decode mp3 I (3457) BTDM_INIT: BT controller compile version [79dd6f1] W (3457) BT_AV: BT Sink pin code is: [2513] I (3467) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE [00:00:01.851722] discover_server:828 sending discovery [00:00:01.871471] discover_server:832 error sending discovery I (5757) wifi: new:<13,2>, old:<1,0>, ap:<255,255>, sta:<13,2>, prof:1 I (6457) wifi: state: init -> auth (b0) I (6467) wifi: state: auth -> assoc (0) I (6467) wifi: state: assoc -> run (10) [00:00:06.876754] discover_server:828 sending discovery [00:00:06.878385] discover_server:832 error sending discovery I (9567) wifi: state: run -> init (fc0) I (9567) wifi: new:<13,0>, old:<13,2>, ap:<255,255>, sta:<13,2>, prof:1 I (9567) wifi: new:<13,0>, old:<13,0>, ap:<255,255>, sta:<13,2>, prof:1 W (9587) wifi_manager: Wifi disconnected. Number of disconnects: 1, Average time connected: 0 E (9587) wifi_manager: WiFi Connection lost. I (9597) wifi_manager: Wifi Connecting... I (9837) wifi: new:<13,2>, old:<13,0>, ap:<255,255>, sta:<13,2>, prof:1 I (9837) wifi: state: init -> auth (b0) I (9847) wifi: state: auth -> assoc (0) I (9857) wifi: state: assoc -> run (10) I (9967) wifi: connected with ktlfhfs, aid = 2, channel 13, 40D, bssid = cc:32:e5:25:da:ba I (9967) wifi: security type: 3, phy: bg, rssi: -32 I (9967) wifi: pm start, type: 1 I (9977) wifi: AP's beacon interval = 102400 us, DTIM period = 1 I (10517) tcpip_adapter: sta ip: 192.168.2.170, mask: 255.255.255.0, gw: 192.168.2.1 I (10527) wifi_manager: SYSTEM_EVENT_STA_GOT_IP. IP=192.168.2.170, Gateway=192.168.2.1, NetMask=255.255.255.0, Address was changed I (10537) esp_app_main: Wifi connected! [00:00:11.876688] discover_server:828 sending discovery [00:00:11.940044] discover_server:840 got response from: 192.168.2.138:3483 [00:00:11.941588] slimproto:942 connecting to 192.168.2.138:3483 [00:00:12.032408] slimproto:989 connected [00:00:12.033402] sendHELO:153 mac: a4:e5:7c:e8:71:e0 [00:00:12.034177] sendHELO:155 cap: Model=squeezeesp32,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Depth=16,Firmware=v1.0-,ModelName=SqueezeESP32,MaxSampleRate=192000,alc,aac,ogg,ops,ogf,flc,wav,aif,pcm,mp3 [00:00:12.074136] notify:223 notified server 192.168.2.138 hport 9000 cport 9090 I (13697) wifi_manager: LMS IP: 192.168.2.138, hport: 9000, cport: 9090 [00:00:12.135952] decode_flush:251 decode flush [00:00:12.136928] output_flush:446 flush output buffer [00:00:12.239634] codec_open:279 codec open: 'o' [00:00:12.240934] stream_sock:508 connecting to 192.168.2.138:9000 [00:00:12.260334] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:00:12.267126] handler:54 got equalizer 14 [00:00:12.692974] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:00:13.531703] output_thread_i2s:480 Output state is 1 [00:00:13.533788] output_thread_i2s:552 Restarting I2S. [00:00:13.536265] equalizer_open:71 equalizer initialized 0 [00:00:13.553323] vorbis_decode:171 setting track_start [00:00:13.928966] _output_frames:65 start buffer frames: 27264 [00:00:13.931764] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 [00:00:13.933218] output_thread_i2s:480 Output state is 2 I (95187) httpd_handlers: serving / to peer 192.168.2.167 port 40141 W (95697) httpd_txrx: httpd_sock_err: error in recv : 104 I (95787) httpd_handlers: serving /config.json to peer 192.168.2.167 port 42701 I (95827) httpd_handlers: serving /commands.json to peer 192.168.2.167 port 45261 I (100827) httpd_handlers: serving /status.json to peer 192.168.2.167 port 41165 [00:03:19.078162] stream_thread:337 end of stream (8694292 bytes) [00:03:31.890500] decode_thread:100 decode complete [00:03:32.021678] codec_open:279 codec open: 'o' [00:03:32.027024] stream_sock:508 connecting to 192.168.2.138:9000 [00:03:32.123061] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:03:32.583679] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:03:32.944339] vorbis_decode:171 setting track_start [00:03:43.327194] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 i2cdetect squeezelite-esp32> [00:12:12.625318] stream_thread:337 end of stream (22521098 bytes) [00:12:25.403751] decode_thread:100 decode complete [00:12:25.525201] codec_open:279 codec open: 'o' [00:12:25.530194] stream_sock:508 connecting to 192.168.2.138:9000 [00:12:25.626645] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:12:25.977742] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:12:26.433686] vorbis_decode:171 setting track_start [00:12:36.817846] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 I (814347) httpd_handlers: serving / to peer 192.168.2.167 port 65250 I (815067) httpd_handlers: serving /config.json to peer 192.168.2.167 port 2787 I (822947) httpd_handlers: serving /status.json to peer 192.168.2.167 port 9443 I (824797) httpd_handlers: serving /status.json to peer 192.168.2.167 port 13027 [00:21:06.093699] stream_thread:337 end of stream (22521098 bytes) [00:21:18.879460] decode_thread:100 decode complete [00:21:19.132152] codec_open:279 codec open: 'o' [00:21:19.137710] stream_sock:508 connecting to 192.168.2.138:9000 [00:21:19.157028] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:21:19.513099] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:21:20.004948] vorbis_decode:171 setting track_start [00:21:30.307803] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 [00:29:46.398183] stream_thread:337 end of stream (10882351 bytes) [00:30:12.155923] decode_thread:100 decode complete [00:30:12.225797] codec_open:279 codec open: 'o' [00:30:12.231143] stream_sock:508 connecting to 192.168.2.138:9000 [00:30:12.241486] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:30:12.598876] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:30:12.971207] vorbis_decode:171 setting track_start [00:30:23.565330] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 [00:38:39.647610] stream_thread:337 end of stream (10882351 bytes) [00:39:05.385785] decode_thread:100 decode complete [00:39:05.531118] codec_open:279 codec open: 'o' [00:39:05.536475] stream_sock:508 connecting to 192.168.2.138:9000 [00:39:05.547903] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:39:05.982948] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:39:06.198723] vorbis_decode:171 setting track_start [00:39:16.800220] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 [00:47:34.774549] stream_thread:337 end of stream (10882351 bytes) [00:47:52.296575] slimproto_run:609 error reading from socket: closed [00:47:57.396673] slimproto:967 unable to connect to server 0 [00:47:58.605587] decode_thread:100 decode complete [00:48:07.396628] slimproto:967 unable to connect to server 1 [00:48:17.396617] slimproto:967 unable to connect to server 2 [00:48:27.396614] slimproto:967 unable to connect to server 3 [00:48:37.396616] slimproto:967 unable to connect to server 4 [00:48:47.396607] slimproto:967 unable to connect to server 5 [00:48:52.397068] discover_server:828 sending discovery [00:48:52.479064] discover_server:840 got response from: 192.168.2.138:3483 [00:48:57.486613] slimproto:967 unable to connect to server 6 [00:49:02.487025] discover_server:828 sending discovery [00:49:02.541041] discover_server:840 got response from: 192.168.2.138:3483 [00:49:05.188027] slimproto:989 connected [00:49:05.189164] sendHELO:153 mac: a4:e5:7c:e8:71:e0 [00:49:05.189943] sendHELO:155 cap: Model=squeezeesp32,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Depth=16,Firmware=v1.0-,ModelName=SqueezeESP32,MaxSampleRate=192000,alc,aac,ogg,ops,ogf,flc,wav,aif,pcm,mp3 [00:49:11.235574] notify:223 notified server 192.168.2.138 hport 9000 cport 9090 I (2952857) wifi_manager: LMS IP: 192.168.2.138, hport: 9000, cport: 9090 [00:49:11.253814] output_thread_i2s:480 Output state is 0 [00:49:30.413370] handler:54 got equalizer 14 [00:49:30.423757] codec_open:279 codec open: 'o' [00:49:30.428471] stream_sock:508 connecting to 192.168.2.138:9000 [00:49:30.435869] equalizer_open:71 equalizer initialized 0 [00:49:34.493800] stream_sock:582 header: GET /stream.mp3?player=a4:e5:7c:e8:71:e0 HTTP/1.0 [00:49:36.898828] stream_thread:264 headers: len: 490 HTTP/1.1 200 OK Server: Logitech Media Server (8.3.0 - 1667251155) Connection: close Content-Type: audio/ogg Set-Cookie: Squeezebox-albumView=; path=/ Set-Cookie: Squeezebox-expandPlayerControl=true; path=/ Set-Cookie: Squeezebox-expanded-MY_MUSIC=1; path=/ Set-Cookie: Squeezebox-expanded-FAVORITES=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGINS=1; path=/ Set-Cookie: Squeezebox-expanded-PLUGIN_MY_APPS_MODULE_NAME=1; path=/ Set-Cookie: Squeezebox-expanded-RADIO=1; path=/ [00:49:41.532575] output_thread_i2s:480 Output state is -1 [00:49:41.535197] output_thread_i2s:484 switching off amp GPIO 21 [00:49:41.536186] output_thread_i2s:486 switching off ext amp GPIO 5 [00:49:41.547253] output_thread_i2s:488 switching on inverted ext amp GPIO 23 [00:50:22.241670] output_thread_i2s:480 Output state is 1 [00:50:22.243507] output_thread_i2s:552 Restarting I2S. [00:50:22.257884] vorbis_decode:171 setting track_start [00:50:22.510799] _output_frames:65 start buffer frames: 27200 [00:50:22.513530] _output_frames:150 track start sample rate: 44100 replay_gain: 65536 [00:50:22.514899] output_thread_i2s:480 Output state is 2 [00:50:25.645379] output_thread_i2s:480 Output state is 0 [00:50:26.390746] output_thread_i2s:480 Output state is 2 [00:59:19.902514] stream_thread:337 end of stream (10882351 bytes) [00:59:42.775941] decode_thread:100 decode complete [00:59:54.302191] output_thread_i2s:480 Output state is 0 [00:59:56.242721] slimproto_run:609 error reading from socket: closed [01:00:01.346625] slimproto:967 unable to connect to server 0 [01:00:09.886864] slimproto:989 connected [01:00:09.888002] sendHELO:153 mac: a4:e5:7c:e8:71:e0 [01:00:09.888774] sendHELO:155 cap: Model=squeezeesp32,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Depth=16,Firmware=v1.0-,ModelName=SqueezeESP32,MaxSampleRate=192000,alc,aac,ogg,ops,ogf,flc,wav,aif,pcm,mp3 [01:00:13.132032] notify:223 notified server 192.168.2.138 hport 9000 cport 9090 I (3614757) wifi_manager: LMS IP: 192.168.2.138, hport: 9000, cport: 9090 [01:00:21.144179] handler:54 got equalizer 14 [01:00:21.192923] equalizer_open:71 equalizer initialized 0 [01:00:24.348801] output_thread_i2s:480 Output state is -1 [01:00:24.349915] output_thread_i2s:484 switching off amp GPIO 21 [01:00:24.350725] output_thread_i2s:486 switching off ext amp GPIO 5 [01:00:24.361850] output_thread_i2s:488 switching on inverted ext amp GPIO 23 [01:00:33.208728] slimproto_run:609 error reading from socket: closed [01:00:38.306580] slimproto:967 unable to connect to server 0 [01:00:47.640860] slimproto:989 connected [01:00:47.642004] sendHELO:153 mac: a4:e5:7c:e8:71:e0 [01:00:47.642790] sendHELO:155 cap: Model=squeezeesp32,AccuratePlayPoints=1,HasDigitalOut=1,HasPolarityInversion=1,Balance=1,Depth=16,Firmware=v1.0-,ModelName=SqueezeESP32,MaxSampleRate=192000,alc,aac,ogg,ops,ogf,flc,wav,aif,pcm,mp3 [01:00:50.728612] notify:223 notified server 192.168.2.138 hport 9000 cport 9090 I (3652347) wifi_manager: LMS IP: 192.168.2.138, hport: 9000, cport: 9090

sle118 commented 1 year ago

Please install a version from here https://sle118.github.io/squeezelite-esp32-installer/

And see if that makes a difference.

There's also an indication of some network connectivity issues in your logs. The player can't seem to reach out to the server at times. Sometimes windows servers tend to keep unused connections active for too long and run out of sockets. Make sure you try a reboot of the server and run the newest build to see if that helps

FHFS commented 1 year ago

I'll try through the installer.

The other board does not have any connection issues with my LMS server running as a container on my Pi. I'll just chalk it up to a bad board since I have two boards running the same firmware and the other is working just fine.

FHFS commented 1 year ago

I've received a new board, flashed the same firmware and the board is running perfectly.