SPI semaphore VS1053 no sound - Githubissues

Edzelf / ESP32-Radio

Internet radio based on ESP32, VS1053 and a TFT screen.

GNU General Public License v3.0

989 stars 228 forks source link

SPI semaphore VS1053 no sound #283

Open minzukas opened 4 years ago

minzukas commented 4 years ago

One more to no output sound on the VS1053. All connected as per instructions. WEB interface seems to work but no sound on headphones. Tries to read the reported issues however that did not solve my problem. DummyTFT in use as i only have hx1230 display at the moment.

Log:

02:43:49.272 -> ⸮r⸮l⸮|⸮$⸮⸮⸮⸮⸮⸮$⸮⸮,:l⸮;⸮"#⸮$⸮⸮⸮⸮$d$:⸮⸮⸮$8⸮⸮''⸮⸮⸮#$⸮l⸮⸮⸮⸮⸮⸮l⸮⸮$ ?⸮$⸮$;$⸮'&⸮⸮;'#b⸮⸮l⸮⸮⸮⸮⸮⸮nr⸮⸮b⸮""⸮⸮⸮b⸮bb⸮⸮|⸮bbp⸮⸮⸮<⸮b#'⸮⸮<⸮""⸮⸮⸮<⸮#$ ;⸮'⸮⸮⸮⸮⸮>⸮#⸮⸮'&pn⸮⸮bllp⸮n⸮br⸮⸮⸮⸮$"'⸮<"⸮$0⸮'⸮";⸮⸮⸮⸮$⸮⸮#'⸮<#$⸮⸮$8⸮'⸮b⸮⸮bn⸮|bl⸮⸮lp⸮n⸮b⸮⸮#'⸮|bl⸮l⸮⸮l⸮n⸮<⸮⸮⸮⸮⸮⸮` 02:43:49.508 -> D: Starting ESP32-radio running on CPU 1 at 240 MHz. Version Thu, 16 May 2019 12:10:00 GMT. Free memory 289148 02:43:49.508 -> D: Display type is DUMMYTFT 02:43:49.508 -> D: Partition nvs found, 20480 bytes 02:43:49.508 -> D: Read 44 keys from NVS 02:43:49.508 -> D: pin_ir set to 35 02:43:49.508 -> D: pin_enc_clk set to 25 02:43:49.508 -> D: pin_enc_dt set to 26 02:43:49.534 -> D: pin_enc_sw set to 27 02:43:49.534 -> D: pin_tft_cs set to 15 02:43:49.534 -> D: pin_tft_dc set to 2 02:43:49.534 -> D: pin_tft_scl set to -1 02:43:49.534 -> D: pin_tft_sda set to -1 02:43:49.534 -> D: pin_tft_bl set to -1 02:43:49.534 -> D: pin_tft_blx set to -1 02:43:49.534 -> D: pin_sd_cs set to 21 02:43:49.534 -> D: pin_vs_cs set to 5 02:43:49.534 -> D: pin_vs_dcs set to 16 02:43:49.567 -> D: pin_vs_dreq set to 4 02:43:49.567 -> D: pin_shutdown set to -1 02:43:49.567 -> D: pin_shutdownx set to -1 02:43:49.567 -> D: pin_spi_sck set to 18 02:43:49.567 -> D: pin_spi_miso set to 19 02:43:49.567 -> D: pin_spi_mosi set to 23 02:43:49.567 -> D: GPIO0 is HIGH 02:43:49.567 -> D: GPIO2 is LOW, probably no PULL-UP 02:43:49.567 -> D: GPIO4 is HIGH 02:43:49.567 -> D: GPIO5 is HIGH 02:43:49.827 -> D: GPIO12 is HIGH 02:43:49.827 -> D: GPIO13 is HIGH 02:43:49.827 -> D: GPIO14 is HIGH 02:43:49.827 -> D: GPIO15 is HIGH 02:43:49.827 -> D: GPIO16 is HIGH 02:43:49.827 -> D: GPIO17 is HIGH 02:43:49.827 -> D: GPIO18 is HIGH 02:43:49.827 -> D: GPIO19 is HIGH 02:43:49.827 -> D: GPIO21 is HIGH 02:43:49.827 -> D: GPIO22 is HIGH 02:43:49.827 -> D: GPIO23 is HIGH 02:43:49.827 -> D: GPIO25 is HIGH 02:43:49.827 -> D: GPIO26 is HIGH 02:43:49.827 -> D: GPIO27 is HIGH 02:43:49.827 -> D: GPIO32 is HIGH 02:43:49.827 -> D: GPIO33 is HIGH 02:43:49.827 -> D: GPIO34 is LOW, probably no PULL-UP 02:43:49.827 -> D: GPIO35 is LOW, probably no PULL-UP 02:43:49.827 -> D: GPIO39 is LOW, probably no PULL-UP 02:43:49.827 -> D: gpio_00 will execute uppreset = 1 02:43:49.827 -> D: gpio_12 will execute upvolume = 2 02:43:49.827 -> D: gpio_13 will execute downvolume = 2 02:43:49.827 -> D: Enable pin 35 for IR 02:43:49.827 -> D: Start display 02:43:50.197 -> D: SD Card Mount Failed! 02:43:50.197 -> D: Create list with acceptable WiFi networks 02:43:50.197 -> D: Added XXXXX to list of networks 02:43:50.197 -> D: Added SSID2 to list of networks 02:43:50.197 -> D: End adding networks 02:43:50.362 -> D: Scan Networks 02:43:52.685 -> D: Scan completed 02:43:52.685 -> D: Number of available networks: 8 02:43:52.685 -> D: 1 - Balticum12-2 Signal: -70 dBm, Encryption WPA_WPA2_PSK, 02:43:52.685 -> D: 2 - Rubuilijus Signal: -81 dBm, Encryption WPA_WPA2_PSK, 02:43:52.685 -> D: 3 - NETGEAR35 Signal: -81 dBm, Encryption WPA2_PSK, Acceptable 02:43:52.718 -> D: 4 - PS4-B6645C2DBBC4 Signal: -83 dBm, Encryption WPA2_PSK, 02:43:52.718 -> D: 5 - Balticum 12-15 Signal: -86 dBm, Encryption WPA_WPA2_PSK, 02:43:52.718 -> D: 6 - Telia-CA71C9-Greitas Signal: -87 dBm, Encryption WPA2_PSK, 02:43:52.751 -> D: 7 - Odeta Signal: -89 dBm, Encryption WPA2_PSK, 02:43:52.751 -> D: 8 - Telia-BBF4B3-Greitas Signal: -94 dBm, Encryption WPA2_PSK, 02:43:52.751 -> D: End of list 02:43:52.751 -> D: Command: clk_dst with parameter 1 02:43:52.751 -> D: Command: clk_offset with parameter 1 02:43:52.751 -> D: Command: clk_server with parameter pool.ntp.org 02:43:52.751 -> D: Command: gpio_00 with parameter uppreset = 1 02:43:52.751 -> D: Command: gpio_12 with parameter upvolume = 2 02:43:52.751 -> D: Command: gpio_13 with parameter downvolume = 2 02:43:52.751 -> D: Command: ir_40bf with parameter upvolume = 2 02:43:52.751 -> D: Command: ir_c03f with parameter downvolume = 2 02:43:52.784 -> D: Command: lstmods with parameter Wed, 24 Apr 2019 07:37:27 GMT 02:43:52.784 -> D: Command: mqttbroker with parameter none 02:43:52.784 -> D: Command: mqttpasswd with parameter *** 02:43:52.784 -> D: Command: mqttport with parameter 1883 02:43:52.784 -> D: Command: mqttprefix with parameter none 02:43:52.817 -> D: Command: mqttuser with parameter none 02:43:52.817 -> D: Command: pin_enc_clk with parameter 25 02:43:52.817 -> D: Command: pin_enc_dt with parameter 26 02:43:52.817 -> D: Command: pin_enc_sw with parameter 27 02:43:52.817 -> D: Command: pin_ir with parameter 35 02:43:52.817 -> D: Command: pin_sd_cs with parameter 21 02:43:52.817 -> D: Command: pin_tft_cs with parameter 15 02:43:52.817 -> D: Command: pin_tft_dc with parameter 2 02:43:52.817 -> D: Command: pin_vs_cs with parameter 5 02:43:52.817 -> D: Command: pin_vs_dcs with parameter 16 02:43:52.817 -> D: Command: pin_vs_dreq with parameter 4 02:43:52.817 -> D: Command: preset with parameter 6 02:43:52.851 -> D: Command: preset_00 with parameter 109.206.96.34:8100 02:43:52.851 -> D: Command: preset_01 with parameter airspectrum.cdnstream1.com:8114/1648_128 02:43:52.851 -> D: Command: preset_02 with parameter us2.internet-radio.com:8050 02:43:52.851 -> D: Command: preset_03 with parameter airspectrum.cdnstream1.com:8000/1261_192 02:43:52.884 -> D: Command: preset_04 with parameter airspectrum.cdnstream1.com:8008/1604_128 02:43:52.884 -> D: Command: preset_05 with parameter us1.internet-radio.com:8105 02:43:52.884 -> D: Command: preset_06 with parameter icecast.omroep.nl:80/radio1-bb-mp3 02:43:52.884 -> D: Command: preset_07 with parameter 205.164.62.15:10032 02:43:52.884 -> D: Command: preset_08 with parameter skonto.ls.lv:8002/mp3 02:43:52.884 -> D: Command: preset_09 with parameter 94.23.66.155:8106 02:43:52.884 -> D: Command: preset_10 with parameter ihr/IHR_IEDM 02:43:52.884 -> D: Command: preset_11 with parameter ihr/IHR_TRAN 02:43:52.917 -> D: Command: toneha with parameter 0 02:43:52.917 -> D: Command: tonehf with parameter 0 02:43:52.917 -> D: Command: tonela with parameter 0 02:43:52.917 -> D: Command: tonelf with parameter 0 02:43:52.917 -> D: Command: volume with parameter 72 02:43:53.016 -> D: Slow SPI, Testing VS1053 read/write registers... 02:43:53.314 -> D: Fast SPI, Testing VS1053 read/write registers again... 02:43:54.639 -> D: endFillByte is 0 02:43:54.772 -> D: Connect to WiFi 02:43:57.525 -> D: Connected to NETGEAR35 02:43:57.525 -> D: IP = 192.168.1.13 02:44:00.544 -> D: Start server for commands 02:44:00.544 -> D: Network found. Starting mqtt and OTA 02:44:00.544 -> D: MDNS responder started 02:44:01.572 -> D: Rotary encoder is enabled 02:44:01.572 -> D: STOP requested 02:44:01.572 -> D: New preset/file requested (6/0) from icecast.omroep.nl:80/radio1-bb-mp3 02:44:01.572 -> D: Connect to new host icecast.omroep.nl:80/radio1-bb-mp3 02:44:01.572 -> D: Connect to icecast.omroep.nl on port 80, extension /radio1-bb-mp3 02:44:01.605 -> D: Connected to server 02:44:01.605 -> D: nvs_get_str failed 1102 for key basicauth, keylen is 9, len is 150! 02:44:01.605 -> D: Contents: 02:44:01.605 -> D: Duration mp3loop 1 02:44:01.705 -> D: Switch to HEADER 02:44:01.705 -> D: Headerline: Content-Type: audio/mpeg 02:44:01.705 -> D: audio/mpeg seen. 02:44:01.705 -> D: Headerline: icy-br:192 02:44:01.705 -> D: Headerline: ice-audio-info: samplerate=48000;channels=2;bitrate=192 02:44:01.705 -> D: Headerline: icy-br:192 02:44:01.705 -> D: Headerline: icy-genre:Talk 02:44:01.705 -> D: Headerline: icy-metadata:1 02:44:01.738 -> D: Headerline: icy-name:NPO Radio1 02:44:01.738 -> D: Headerline: icy-pub:0 02:44:01.738 -> D: Headerline: icy-url:http://www.radio1.nl 02:44:01.738 -> D: Headerline: Server: Icecast 2.4.0-kh10 02:44:01.738 -> D: Headerline: Cache-Control: no-cache, no-store 02:44:01.738 -> D: Headerline: Access-Control-Allow-Origin: * 02:44:01.738 -> D: Headerline: Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type 02:44:01.738 -> D: l-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type seen. 02:44:01.738 -> D: Headerline: Access-Control-Allow-Methods: GET, OPTIONS, HEAD 02:44:01.738 -> D: Headerline: Connection: Close 02:44:01.771 -> D: Headerline: Expires: Mon, 26 Jul 1997 05:00:00 GMT 02:44:01.771 -> D: Headerline: icy-metaint:16000 02:44:01.771 -> D: Switch to DATA, bitrate is 192, metaint is 16000 02:44:01.771 -> D: Duration mp3loop 71 02:44:01.771 -> D: SPI semaphore not taken within 120 ticks by CPU 0, id hspec 02:44:01.771 -> D: SPI semaphore not taken within 130 ticks by CPU 0, id hspec 02:44:01.771 -> D: SPI semaphore not taken within 140 ticks by CPU 0, id hspec 02:44:01.804 -> D: SPI semaphore not taken within 150 ticks by CPU 0, id hspec 02:44:01.804 -> D: SPI semaphore not taken within 160 ticks by CPU 0, id hspec 02:44:01.804 -> D: SPI semaphore not taken within 170 ticks by CPU 0, id hspec 02:44:01.837 -> D: SPI semaphore not taken within 180 ticks by CPU 1, id hspec 02:44:01.837 -> D: SPI semaphore not taken within 190 ticks by CPU 0, id hspec 02:44:01.837 -> D: SPI semaphore not taken within 200 ticks by CPU 0, id hspec 02:44:01.837 -> D: SPI semaphore not taken within 210 ticks by CPU 0, id hspec 02:44:01.870 -> D: SPI semaphore not taken within 220 ticks by CPU 0, id hspec 02:44:01.870 -> D: SPI semaphore not taken within 230 ticks by CPU 0, id hspec 02:44:01.870 -> D: SPI semaphore not taken within 240 ticks by CPU 0, id hspec 02:44:01.904 -> D: SPI semaphore not taken within 250 ticks by CPU 0, id hspec 02:44:01.904 -> D: SPI semaphore not taken within 260 ticks by CPU 0, id hspec 02:44:01.904 -> D: SPI semaphore not taken within 270 ticks by CPU 0, id hspec 02:44:01.937 -> D: SPI semaphore not taken within 280 ticks by CPU 0, id hspec 02:44:01.937 -> D: SPI semaphore not taken within 290 ticks by CPU 0, id hspec 02:44:01.937 -> D: SPI semaphore not taken within 300 ticks by CPU 0, id hspec 02:44:01.937 -> D: SPI semaphore not taken within 310 ticks by CPU 0, id hspec 02:44:01.970 -> D: SPI semaphore not taken within 320 ticks by CPU 0, id hspec 02:44:01.970 -> D: SPI semaphore not taken within 330 ticks by CPU 0, id hspec 02:44:01.970 -> D: SPI semaphore not taken within 340 ticks by CPU 0, id hspec 02:44:02.003 -> D: SPI semaphore not taken within 350 ticks by CPU 0, id hspec 02:44:02.003 -> D: SPI semaphore not taken within 360 ticks by CPU 0, id hspec 02:44:02.003 -> D: SPI semaphore not taken within 370 ticks by CPU 0, id hspec 02:44:02.036 -> D: SPI semaphore not taken within 380 ticks by CPU 0, id hspec 02:44:02.036 -> D: SPI semaphore not taken within 390 ticks by CPU 0, id hspec 02:44:02.036 -> D: SPI semaphore not taken within 400 ticks by CPU 0, id hspec 02:44:02.069 -> D: SPI semaphore not taken within 410 ticks by CPU 0, id hspec 02:44:02.069 -> D: SPI semaphore not taken within 420 ticks by CPU 0, id hspec 02:44:02.069 -> D: SPI semaphore not taken within 430 ticks by CPU 0, id hspec 02:44:02.069 -> D: SPI semaphore not taken within 440 ticks by CPU 0, id hspec 02:44:02.103 -> D: SPI semaphore not taken within 450 ticks by CPU 0, id hspec 02:44:02.103 -> D: SPI semaphore not taken within 460 ticks by CPU 0, id hspec 02:44:02.103 -> D: SPI semaphore not taken within 470 ticks by CPU 0, id hspec 02:44:02.136 -> D: SPI semaphore not taken within 480 ticks by CPU 0, id hspec 02:44:02.136 -> D: SPI semaphore not taken within 490 ticks by CPU 0, id hspec 02:44:02.136 -> D: SPI semaphore not taken within 500 ticks by CPU 0, id hspec 02:44:02.169 -> D: SPI semaphore not taken within 510 ticks by CPU 0, id hspec 02:44:02.169 -> D: SPI semaphore not taken within 520 ticks by CPU 0, id hspec 02:44:02.169 -> D: SPI semaphore not taken within 530 ticks by CPU 0, id hspec 02:44:02.169 -> D: SPI semaphore not taken within 540 ticks by CPU 0, id hspec 02:44:02.202 -> D: SPI semaphore not taken within 550 ticks by CPU 0, id hspec 02:44:02.202 -> D: SPI semaphore not taken within 560 ticks by CPU 0, id hspec 02:44:02.202 -> D: SPI semaphore not taken within 570 ticks by CPU 0, id hspec 02:44:02.235 -> D: SPI semaphore not taken within 580 ticks by CPU 0, id hspec 02:44:02.235 -> D: Duration mp3loop 401 02:44:02.235 -> D: SPI semaphore not taken within 590 ticks by CPU 0, id hspec 02:44:02.235 -> D: SPI semaphore not taken within 600 ticks by CPU 0, id hspec 02:44:02.268 -> D: SPI semaphore not taken within 610 ticks by CPU 0, id hspec 02:44:02.268 -> D: SPI semaphore not taken within 620 ticks by CPU 0, id hspec 02:44:02.268 -> D: SPI semaphore not taken within 630 ticks by CPU 0, id hspec 02:44:02.268 -> D: SPI semaphore not taken within 640 ticks by CPU 0, id hspec 02:44:02.302 -> D: SPI semaphore not taken within 650 ticks by CPU 0, id hspec 02:44:02.302 -> D: SPI semaphore not taken within 660 ticks by CPU 0, id hspec 02:44:02.302 -> D: SPI semaphore not taken within 670 ticks by CPU 0, id hspec 02:44:02.335 -> D: SPI semaphore not taken within 680 ticks by CPU 0, id hspec 02:44:02.335 -> D: SPI semaphore not taken within 690 ticks by CPU 0, id hspec 02:44:02.335 -> D: SPI semaphore not taken within 700 ticks by CPU 0, id hspec 02:44:02.368 -> D: SPI semaphore not taken within 710 ticks by CPU 0, id hspec 02:44:02.368 -> D: SPI semaphore not taken within 720 ticks by CPU 0, id hspec 02:44:02.368 -> D: SPI semaphore not taken within 730 ticks by CPU 0, id hspec 02:44:02.368 -> D: SPI semaphore not taken within 740 ticks by CPU 0, id hspec 02:44:02.401 -> D: SPI semaphore not taken within 750 ticks by CPU 0, id hspec 02:44:02.401 -> D: SPI semaphore not taken within 760 ticks by CPU 0, id hspec 02:44:02.401 -> D: SPI semaphore not taken within 770 ticks by CPU 0, id hspec 02:44:02.434 -> D: SPI semaphore not taken within 780 ticks by CPU 0, id hspec 02:44:02.434 -> D: SPI semaphore not taken within 790 ticks by CPU 0, id hspec 02:44:02.434 -> D: SPI semaphore not taken within 800 ticks by CPU 0, id hspec 02:44:02.468 -> D: SPI semaphore not taken within 810 ticks by CPU 0, id hspec 02:44:02.468 -> D: SPI semaphore not taken within 820 ticks by CPU 0, id hspec 02:44:02.468 -> D: SPI semaphore not taken within 830 ticks by CPU 0, id hspec 02:44:02.468 -> D: SPI semaphore not taken within 840 ticks by CPU 0, id hspec 02:44:02.501 -> D: SPI semaphore not taken within 850 ticks by CPU 0, id hspec 02:44:02.501 -> D: SPI semaphore not taken within 860 ticks by CPU 0, id hspec 02:44:02.501 -> D: SPI semaphore not taken within 870 ticks by CPU 0, id hspec 02:44:02.534 -> D: SPI semaphore not taken within 880 ticks by CPU 0, id hspec 02:44:02.534 -> D: SPI semaphore not taken within 890 ticks by CPU 0, id hspec 02:44:02.534 -> D: SPI semaphore not taken within 900 ticks by CPU 0, id hspec 02:44:02.567 -> D: SPI semaphore not taken within 910 ticks by CPU 0, id hspec 02:44:02.567 -> D: SPI semaphore not taken within 920 ticks by CPU 0, id hspec 02:44:02.567 -> D: SPI semaphore not taken within 930 ticks by CPU 0, id hspec 02:44:02.567 -> D: SPI semaphore not taken within 940 ticks by CPU 0, id hspec 02:44:02.600 -> D: SPI semaphore not taken within 950 ticks by CPU 0, id hspec 02:44:02.600 -> D: SPI semaphore not taken within 960 ticks by CPU 0, id hspec 02:44:02.600 -> D: SPI semaphore not taken within 970 ticks by CPU 0, id hspec 02:44:02.633 -> D: SPI semaphore not taken within 980 ticks by CPU 0, id hspec 02:44:02.633 -> D: SPI semaphore not taken within 990 ticks by CPU 0, id hspec 02:44:02.633 -> D: SPI semaphore not taken within 1000 ticks by CPU 0, id hspec 02:44:02.666 -> D: SPI semaphore not taken within 1010 ticks by CPU 0, id hspec 02:44:02.666 -> D: SPI semaphore not taken within 1020 ticks by CPU 0, id hspec 02:44:02.666 -> D: SPI semaphore not taken within 1030 ticks by CPU 0, id hspec 02:44:02.699 -> D: SPI semaphore not taken within 1040 ticks by CPU 0, id hspec 02:44:02.699 -> D: SPI semaphore not taken within 1050 ticks by CPU 0, id hspec 02:44:02.699 -> D: SPI semaphore not taken within 1060 ticks by CPU 0, id hspec 02:44:02.699 -> D: SPI semaphore not taken within 1070 ticks by CPU 0, id hspec 02:44:02.733 -> D: SPI semaphore not taken within 1080 ticks by CPU 0, id hspec 02:44:02.733 -> D: SPI semaphore not taken within 1090 ticks by CPU 0, id hspec 02:44:02.733 -> D: SPI semaphore not taken within 1100 ticks by CPU 0, id hspec 02:44:02.766 -> D: SPI semaphore not taken within 1110 ticks by CPU 0, id hspec 02:44:02.766 -> D: SPI semaphore not taken within 1120 ticks by CPU 0, id hspec 02:44:02.766 -> D: SPI semaphore not taken within 1130 ticks by CPU 0, id hspec 02:44:02.799 -> D: SPI semaphore not taken within 1140 ticks by CPU 0, id hspec 02:44:02.799 -> D: SPI semaphore not taken within 1150 ticks by CPU 0, id hspec 02:44:02.799 -> D: SPI semaphore not taken within 1160 ticks by CPU 0, id hspec 02:44:02.799 -> D: SPI semaphore not taken within 1170 ticks by CPU 0, id hspec 02:44:02.832 -> D: SPI semaphore not taken within 1180 ticks by CPU 0, id hspec 02:44:02.832 -> D: SPI semaphore not taken within 1190 ticks by CPU 0, id hspec 02:44:02.832 -> D: SPI semaphore not taken within 1200 ticks by CPU 0, id hspec 02:44:02.865 -> D: SPI semaphore not taken within 1210 ticks by CPU 0, id hspec 02:44:02.865 -> D: SPI semaphore not taken within 1220 ticks by CPU 0, id hspec 02:44:02.865 -> D: SPI semaphore not taken within 1230 ticks by CPU 0, id hspec 02:44:02.899 -> D: SPI semaphore not taken within 1240 ticks by CPU 0, id hspec 02:44:02.899 -> D: SPI semaphore not taken within 1250 ticks by CPU 0, id hspec 02:44:02.899 -> D: SPI semaphore not taken within 1260 ticks by CPU 0, id hspec 02:44:02.899 -> D: SPI semaphore not taken within 1270 ticks by CPU 0, id hspec 02:44:02.932 -> D: SPI semaphore not taken within 1280 ticks by CPU 0, id hspec 02:44:02.932 -> D: SPI semaphore not taken within 1290 ticks by CPU 0, id hspec 02:44:02.932 -> D: SPI semaphore not taken within 1300 ticks by CPU 0, id hspec 02:44:02.965 -> D: SPI semaphore not taken within 1310 ticks by CPU 0, id hspec 02:44:02.965 -> D: SPI semaphore not taken within 1320 ticks by CPU 0, id hspec 02:44:02.965 -> D: SPI semaphore not taken within 1330 ticks by CPU 0, id hspec 02:44:02.998 -> D: SPI semaphore not taken within 1340 ticks by CPU 0, id hspec 02:44:02.998 -> D: SPI semaphore not taken within 1350 ticks by CPU 0, id hspec 02:44:02.998 -> D: SPI semaphore not taken within 1360 ticks by CPU 0, id hspec 02:44:02.998 -> D: SPI semaphore not taken within 1370 ticks by CPU 0, id hspec 02:44:03.031 -> D: SPI semaphore not taken within 1380 ticks by CPU 0, id hspec 02:44:03.031 -> D: SPI semaphore not taken within 1390 ticks by CPU 0, id hspec 02:44:03.031 -> D: SPI semaphore not taken within 1400 ticks by CPU 0, id hspec 02:44:03.064 -> D: SPI semaphore not taken within 1410 ticks by CPU 0, id hspec 02:44:03.064 -> D: SPI semaphore not taken within 1420 ticks by CPU 0, id hspec 02:44:03.064 -> D: SPI semaphore not taken within 1430 ticks by CPU 0, id hspec 02:44:03.098 -> D: SPI semaphore not taken within 1440 ticks by CPU 0, id hspec 02:44:03.098 -> D: SPI semaphore not taken within 1450 ticks by CPU 0, id hspec 02:44:03.098 -> D: SPI semaphore not taken within 1460 ticks by CPU 0, id hspec 02:44:03.098 -> D: SPI semaphore not taken within 1470 ticks by CPU 0, id hspec 02:44:03.131 -> D: SPI semaphore not taken within 1480 ticks by CPU 0, id hspec 02:44:03.131 -> D: SPI semaphore not taken within 1490 ticks by CPU 0, id hspec 02:44:03.131 -> D: SPI semaphore not taken within 1500 ticks by CPU 0, id hspec 02:44:03.164 -> D: SPI semaphore not taken within 1510 ticks by CPU 0, id hspec 02:44:03.164 -> D: SPI semaphore not taken within 1520 ticks by CPU 0, id hspec 02:44:03.164 -> D: SPI semaphore not taken within 1530 ticks by CPU 0, id hspec 02:44:03.197 -> D: SPI semaphore not taken within 1540 ticks by CPU 0, id hspec 02:44:03.197 -> D: SPI semaphore not taken within 1550 ticks by CPU 0, id hspec 02:44:03.197 -> D: SPI semaphore not taken within 1560 ticks by CPU 0, id hspec 02:44:03.197 -> D: SPI semaphore not taken within 1570 ticks by CPU 0, id hspec 02:44:03.230 -> D: SPI semaphore not taken within 1580 ticks by CPU 0, id hspec 02:44:03.230 -> D: SPI semaphore not taken within 1590 ticks by CPU 0, id hspec 02:44:03.230 -> D: SPI semaphore not taken within 1600 ticks by CPU 0, id hspec 02:44:03.263 -> D: SPI semaphore not taken within 1610 ticks by CPU 0, id hspec 02:44:03.263 -> D: SPI semaphore not taken within 1620 ticks by CPU 0, id hspec 02:44:03.263 -> D: SPI semaphore not taken within 1630 ticks by CPU 0, id hspec 02:44:03.296 -> D: SPI semaphore not taken within 1640 ticks by CPU 0, id hspec 02:44:03.296 -> D: SPI semaphore not taken within 1650 ticks by CPU 0, id hspec 02:44:03.296 -> D: SPI semaphore not taken within 1660 ticks by CPU 0, id hspec 02:44:03.330 -> D: SPI semaphore not taken within 1670 ticks by CPU 0, id hspec 02:44:03.330 -> D: SPI semaphore not taken within 1680 ticks by CPU 0, id hspec 02:44:03.330 -> D: SPI semaphore not taken within 1690 ticks by CPU 0, id hspec 02:44:03.330 -> D: SPI semaphore not taken within 1700 ticks by CPU 0, id hspec 02:44:03.363 -> D: SPI semaphore not taken within 1710 ticks by CPU 0, id hspec 02:44:03.363 -> D: SPI semaphore not taken within 1720 ticks by CPU 0, id hspec 02:44:03.363 -> D: SPI semaphore not taken within 1730 ticks by CPU 0, id hspec 02:44:03.396 -> D: SPI semaphore not taken within 1740 ticks by CPU 0, id hspec 02:44:03.396 -> D: SPI semaphore not taken within 1750 ticks by CPU 0, id hspec 02:44:03.396 -> D: SPI semaphore not taken within 1760 ticks by CPU 0, id hspec 02:44:03.396 -> D: SPI semaphore not taken within 1770 ticks by CPU 0, id hspec 02:44:03.429 -> D: SPI semaphore not taken within 1780 ticks by CPU 0, id hspec 02:44:03.429 -> D: SPI semaphore not taken within 1790 ticks by CPU 0, id hspec 02:44:03.429 -> D: SPI semaphore not taken within 1800 ticks by CPU 0, id hspec 02:44:03.462 -> D: SPI semaphore not taken within 1810 ticks by CPU 0, id hspec 02:44:03.462 -> D: SPI semaphore not taken within 1820 ticks by CPU 0, id hspec 02:44:03.462 -> D: SPI semaphore not taken within 1830 ticks by CPU 0, id hspec 02:44:03.495 -> D: SPI semaphore not taken within 1840 ticks by CPU 0, id hspec 02:44:03.495 -> D: SPI semaphore not taken within 1850 ticks by CPU 0, id hspec 02:44:03.495 -> D: SPI semaphore not taken within 1860 ticks by CPU 0, id hspec 02:44:03.528 -> D: SPI semaphore not taken within 1870 ticks by CPU 0, id hspec 02:44:03.528 -> D: SPI semaphore not taken within 1880 ticks by CPU 0, id hspec 02:44:03.528 -> D: SPI semaphore not taken within 1890 ticks by CPU 0, id hspec 02:44:03.528 -> D: SPI semaphore not taken within 1900 ticks by CPU 0, id hspec 02:44:03.562 -> D: SPI semaphore not taken within 1910 ticks by CPU 0, id hspec 02:44:03.562 -> D: Song stopped incorrectly! 02:44:03.562 -> D: REG Contents 02:44:03.562 -> D: --- ----- 02:44:03.562 -> D: SPI semaphore not taken within 1920 ticks by CPU 0, id hspec 02:44:03.562 -> D: 0 - 808 02:44:03.562 -> D: 1 - 33 02:44:03.562 -> D: SPI semaphore not taken within 1930 ticks by CPU 0, id hspec 02:44:03.562 -> D: 2 - 0 02:44:03.562 -> D: 3 - 6000 02:44:03.595 -> D: SPI semaphore not taken within 1940 ticks by CPU 0, id hspec 02:44:03.595 -> D: 4 - 0 02:44:03.595 -> D: 5 - AC45 02:44:03.595 -> D: SPI semaphore not taken within 1950 ticks by CPU 0, id hspec 02:44:03.595 -> D: 6 - 1800 02:44:03.595 -> D: 7 - 1E06 02:44:03.628 -> D: SPI semaphore not taken within 1960 ticks by CPU 0, id hspec 02:44:03.628 -> D: 8 - 0 02:44:03.628 -> D: 9 - 0 02:44:03.628 -> D: SPI semaphore not taken within 1970 ticks by CPU 0, id hspec 02:44:03.628 -> D: A - 0 02:44:03.628 -> D: B - F8F8 02:44:03.628 -> D: SPI semaphore not taken within 1980 ticks by CPU 0, id hspec 02:44:03.628 -> D: C - 0 02:44:03.628 -> D: D - 0 02:44:03.628 -> D: SPI semaphore not taken within 1990 ticks by CPU 0, id hspec 02:44:03.628 -> D: E - 0 02:44:03.628 -> D: F - 0 02:44:04.159 -> D: Metadata block 64 bytes 02:44:04.159 -> D: Streamtitle found, 53 bytes 02:44:04.159 -> D: StreamTitle='NPO Radio 1 - Zwarte Prietpraat - POWN';

Edzelf commented 4 years ago

I tried to reproduce this error, but without success. I have changed the claimSPI() function to allow some more debug logging. Could you try this new version?

minzukas commented 4 years ago

Thanks, will try it after the work

minzukas commented 4 years ago

here it is with the new code:

19:29:07.368 -> ⸮⸮⸮r⸮l⸮|⸮l⸮⸮⸮⸮⸮⸮l⸮⸮lrl⸮r⸮bb⸮l⸮⸮⸮⸮lllr⸮⸮⸮lp⸮⸮n'⸮⸮⸮#$⸮$⸮⸮⸮⸮⸮⸮$⸮⸮$ ?⸮$⸮$;$⸮''⸮⸮;'#b⸮⸮l⸮⸮⸮⸮⸮⸮nr⸮⸮b⸮bb⸮⸮⸮b⸮bb⸮⸮|⸮b#8⸮⸮⸮<⸮bbn⸮⸮<⸮##⸮⸮⸮<⸮#$ ;⸮'⸮⸮⸮⸮⸮~⸮b⸮onpn⸮⸮⸮"$$ 8⸮'⸮":⸮⸮⸮⸮$#'⸮<#⸮$8⸮'⸮#;⸮⸮⸮⸮l⸮bn⸮|bl⸮⸮$8⸮'⸮"⸮⸮⸮#n⸮|bl⸮⸮lp⸮n⸮#⸮⸮bn⸮|bl⸮l⸮⸮l⸮n⸮|⸮⸮⸮⸮⸮⸮ 19:29:07.550 -> D: Starting ESP32-radio running on CPU 1 at 240 MHz. Version Thu, 16 Dec 2019 09:07:00 GMT. Free memory 289284 19:29:07.550 -> D: Display type is DUMMYTFT 19:29:07.550 -> D: Partition nvs found, 20480 bytes 19:29:07.550 -> D: Read 43 keys from NVS 19:29:07.550 -> D: pin_ir set to 35 19:29:07.550 -> D: pin_enc_clk set to 25 19:29:07.550 -> D: pin_enc_dt set to 26 19:29:07.550 -> D: pin_enc_sw set to 27 19:29:07.550 -> D: pin_tft_cs set to 15 19:29:07.550 -> D: pin_tft_dc set to 2 19:29:07.550 -> D: pin_tft_scl set to -1 19:29:07.550 -> D: pin_tft_sda set to -1 19:29:07.550 -> D: pin_tft_bl set to -1 19:29:07.550 -> D: pin_tft_blx set to -1 19:29:07.550 -> D: pin_sd_cs set to 21 19:29:07.550 -> D: pin_vs_cs set to 5 19:29:07.550 -> D: pin_vs_dcs set to 16 19:29:07.550 -> D: pin_vs_dreq set to 4 19:29:07.550 -> D: pin_shutdown set to -1 19:29:07.550 -> D: pin_shutdownx set to -1 19:29:07.550 -> D: pin_spi_sck set to 18 19:29:07.550 -> D: pin_spi_miso set to 19 19:29:07.550 -> D: pin_spi_mosi set to 23 19:29:07.550 -> D: GPIO0 is HIGH 19:29:07.928 -> D: GPIO2 is LOW, probably no PULL-UP 19:29:07.928 -> D: GPIO4 is HIGH 19:29:07.928 -> D: GPIO5 is HIGH 19:29:07.928 -> D: GPIO12 is HIGH 19:29:07.928 -> D: GPIO13 is HIGH 19:29:07.928 -> D: GPIO14 is HIGH 19:29:07.928 -> D: GPIO15 is HIGH 19:29:07.928 -> D: GPIO16 is HIGH 19:29:07.928 -> D: GPIO17 is HIGH 19:29:07.928 -> D: GPIO18 is HIGH 19:29:07.928 -> D: GPIO19 is HIGH 19:29:07.928 -> D: GPIO21 is HIGH 19:29:07.928 -> D: GPIO22 is HIGH 19:29:07.928 -> D: GPIO23 is HIGH 19:29:07.928 -> D: GPIO25 is LOW, probably no PULL-UP 19:29:07.928 -> D: GPIO26 is LOW, probably no PULL-UP 19:29:07.928 -> D: GPIO27 is HIGH 19:29:07.928 -> D: GPIO32 is HIGH 19:29:07.928 -> D: GPIO33 is HIGH 19:29:07.928 -> D: GPIO34 is LOW, probably no PULL-UP 19:29:07.928 -> D: GPIO35 is LOW, probably no PULL-UP 19:29:07.928 -> D: GPIO39 is LOW, probably no PULL-UP 19:29:07.928 -> D: gpio_00 will execute uppreset = 1 19:29:07.928 -> D: gpio_12 will execute upvolume = 2 19:29:07.928 -> D: gpio_13 will execute downvolume = 2 19:29:07.928 -> D: Enable pin 35 for IR 19:29:07.928 -> D: Start display 19:29:08.044 -> D: SD Card Mount Failed! 19:29:08.044 -> D: Create list with acceptable WiFi networks 19:29:08.044 -> D: Added NETGEAR35 to list of networks 19:29:08.044 -> D: Added SSID2 to list of networks 19:29:08.044 -> D: End adding networks 19:29:08.210 -> D: Scan Networks 19:29:10.337 -> D: Scan completed 19:29:10.337 -> D: Number of available networks: 7 19:29:10.337 -> D: 1 - Balticum 12-15 Signal: -73 dBm, Encryption WPA_WPA2_PSK, 19:29:10.337 -> D: 2 - NETGEAR35 Signal: -74 dBm, Encryption WPA2_PSK, Acceptable 19:29:10.337 -> D: 3 - Balticum12-2 Signal: -80 dBm, Encryption WPA_WPA2_PSK, 19:29:10.372 -> D: 4 - PS4-B6645C2DBBC4 Signal: -84 dBm, Encryption WPA2_PSK, 19:29:10.372 -> D: 5 - Rubuilijus Signal: -84 dBm, Encryption WPA_WPA2_PSK, 19:29:10.372 -> D: 6 - Telia-CA71C9-Greitas Signal: -91 dBm, Encryption WPA2_PSK, 19:29:10.372 -> D: 7 - Telia-BBF4B3-Greitas Signal: -92 dBm, Encryption WPA2_PSK, 19:29:10.372 -> D: End of list 19:29:10.372 -> D: Command: clk_dst with parameter 1 19:29:10.372 -> D: Command: clk_offset with parameter 1 19:29:10.372 -> D: Command: clk_server with parameter pool.ntp.org 19:29:10.372 -> D: Command: gpio_00 with parameter uppreset = 1 19:29:10.403 -> D: Command: gpio_12 with parameter upvolume = 2 19:29:10.403 -> D: Command: gpio_13 with parameter downvolume = 2 19:29:10.403 -> D: Command: ir_40bf with parameter upvolume = 2 19:29:10.403 -> D: Command: ir_c03f with parameter downvolume = 2 19:29:10.403 -> D: Command: mqttbroker with parameter none 19:29:10.436 -> D: Command: mqttpasswd with parameter *** 19:29:10.436 -> D: Command: mqttport with parameter 1883 19:29:10.436 -> D: Command: mqttprefix with parameter none 19:29:10.436 -> D: Command: mqttuser with parameter none 19:29:10.436 -> D: Command: pin_enc_clk with parameter 25 19:29:10.436 -> D: Command: pin_enc_dt with parameter 26 19:29:10.436 -> D: Command: pin_enc_sw with parameter 27 19:29:10.436 -> D: Command: pin_ir with parameter 35 19:29:10.436 -> D: Command: pin_sd_cs with parameter 21 19:29:10.436 -> D: Command: pin_tft_cs with parameter 15 19:29:10.436 -> D: Command: pin_tft_dc with parameter 2 19:29:10.436 -> D: Command: pin_vs_cs with parameter 5 19:29:10.470 -> D: Command: pin_vs_dcs with parameter 16 19:29:10.470 -> D: Command: pin_vs_dreq with parameter 4 19:29:10.470 -> D: Command: preset with parameter 6 19:29:10.470 -> D: Command: preset_00 with parameter 109.206.96.34:8100 19:29:10.470 -> D: Command: preset_01 with parameter airspectrum.cdnstream1.com:8114/1648_128 19:29:10.503 -> D: Command: preset_02 with parameter us2.internet-radio.com:8050 19:29:10.503 -> D: Command: preset_03 with parameter airspectrum.cdnstream1.com:8000/1261_192 19:29:10.503 -> D: Command: preset_04 with parameter airspectrum.cdnstream1.com:8008/1604_128 19:29:10.503 -> D: Command: preset_05 with parameter us1.internet-radio.com:8105 19:29:10.503 -> D: Command: preset_06 with parameter icecast.omroep.nl:80/radio1-bb-mp3 19:29:10.503 -> D: Command: preset_07 with parameter 205.164.62.15:10032 19:29:10.503 -> D: Command: preset_08 with parameter skonto.ls.lv:8002/mp3 19:29:10.503 -> D: Command: preset_09 with parameter 94.23.66.155:8106 19:29:10.536 -> D: Command: preset_10 with parameter ihr/IHR_IEDM 19:29:10.536 -> D: Command: preset_11 with parameter ihr/IHR_TRAN 19:29:10.536 -> D: Command: toneha with parameter 0 19:29:10.536 -> D: Command: tonehf with parameter 0 19:29:10.536 -> D: Command: tonela with parameter 0 19:29:10.536 -> D: Command: tonelf with parameter 0 19:29:10.536 -> D: Command: volume with parameter 72 19:29:10.668 -> D: Slow SPI, Testing VS1053 read/write registers... 19:29:10.934 -> D: Fast SPI, Testing VS1053 read/write registers again... 19:29:12.295 -> D: endFillByte is 0 19:29:12.395 -> D: Connect to WiFi 19:29:16.612 -> D: WiFi Failed! Trying to setup AP with name ESP32Radio and password ESP32Radio. 19:29:16.612 -> D: IP = 192.168.4.1 19:29:19.599 -> D: Start server for commands 19:29:20.629 -> D: Rotary encoder is enabled 19:29:20.629 -> D: STOP requested 19:29:20.861 -> D: SPI semaphore not taken within 120 ticks by CPU 0, id hspec 19:29:20.861 -> D: Semaphore is claimed by stopsong 19:29:20.861 -> D: SPI semaphore not taken within 130 ticks by CPU 0, id hspec 19:29:20.861 -> D: Semaphore is claimed by stopsong 19:29:20.861 -> D: SPI semaphore not taken within 140 ticks by CPU 0, id hspec 19:29:20.861 -> D: Semaphore is claimed by stopsong 19:29:20.894 -> D: SPI semaphore not taken within 150 ticks by CPU 0, id hspec 19:29:20.894 -> D: Semaphore is claimed by stopsong 19:29:20.894 -> D: SPI semaphore not taken within 160 ticks by CPU 0, id hspec 19:29:20.894 -> D: Semaphore is claimed by stopsong 19:29:20.894 -> D: SPI semaphore not taken within 170 ticks by CPU 0, id hspec 19:29:20.894 -> D: Semaphore is claimed by stopsong 19:29:20.928 -> D: SPI semaphore not taken within 180 ticks by CPU 0, id hspec 19:29:20.928 -> D: Semaphore is claimed by stopsong 19:29:20.928 -> D: SPI semaphore not taken within 190 ticks by CPU 0, id hspec 19:29:20.928 -> D: Semaphore is claimed by stopsong 19:29:20.928 -> D: SPI semaphore not taken within 200 ticks by CPU 0, id hspec 19:29:20.928 -> D: Semaphore is claimed by stopsong 19:29:20.928 -> D: SPI semaphore not taken within 210 ticks by CPU 0, id hspec 19:29:20.928 -> D: Semaphore is claimed by stopsong 19:29:20.961 -> D: SPI semaphore not taken within 220 ticks by CPU 0, id hspec 19:29:20.961 -> D: Semaphore is claimed by stopsong 19:29:20.961 -> D: SPI semaphore not taken within 230 ticks by CPU 0, id hspec 19:29:20.961 -> D: Semaphore is claimed by stopsong 19:29:20.961 -> D: SPI semaphore not taken within 240 ticks by CPU 0, id hspec 19:29:20.961 -> D: Semaphore is claimed by stopsong 19:29:20.994 -> D: SPI semaphore not taken within 250 ticks by CPU 0, id hspec 19:29:20.994 -> D: Semaphore is claimed by stopsong 19:29:20.994 -> D: SPI semaphore not taken within 260 ticks by CPU 0, id hspec 19:29:20.994 -> D: Semaphore is claimed by stopsong 19:29:20.994 -> D: SPI semaphore not taken within 270 ticks by CPU 0, id hspec 19:29:20.994 -> D: Semaphore is claimed by stopsong 19:29:20.994 -> D: SPI semaphore not taken within 280 ticks by CPU 0, id hspec 19:29:20.994 -> D: Semaphore is claimed by stopsong 19:29:21.027 -> D: SPI semaphore not taken within 290 ticks by CPU 0, id hspec 19:29:21.027 -> D: Semaphore is claimed by stopsong 19:29:21.027 -> D: SPI semaphore not taken within 300 ticks by CPU 0, id hspec 19:29:21.027 -> D: Semaphore is claimed by stopsong 19:29:21.027 -> D: SPI semaphore not taken within 310 ticks by CPU 0, id hspec 19:29:21.027 -> D: Semaphore is claimed by stopsong 19:29:21.060 -> D: SPI semaphore not taken within 320 ticks by CPU 0, id hspec 19:29:21.060 -> D: Semaphore is claimed by stopsong 19:29:21.060 -> D: SPI semaphore not taken within 330 ticks by CPU 0, id hspec 19:29:21.060 -> D: Semaphore is claimed by stopsong 19:29:21.093 -> D: SPI semaphore not taken within 340 ticks by CPU 0, id hspec 19:29:21.093 -> D: Semaphore is claimed by stopsong 19:29:21.093 -> D: SPI semaphore not taken within 350 ticks by CPU 0, id hspec 19:29:21.093 -> D: Semaphore is claimed by stopsong 19:29:21.093 -> D: SPI semaphore not taken within 360 ticks by CPU 0, id hspec 19:29:21.093 -> D: Semaphore is claimed by stopsong 19:29:21.093 -> D: SPI semaphore not taken within 370 ticks by CPU 0, id hspec 19:29:21.093 -> D: Semaphore is claimed by stopsong 19:29:21.127 -> D: SPI semaphore not taken within 380 ticks by CPU 0, id hspec 19:29:21.127 -> D: Semaphore is claimed by stopsong 19:29:21.127 -> D: SPI semaphore not taken within 390 ticks by CPU 0, id hspec 19:29:21.127 -> D: Semaphore is claimed by stopsong 19:29:21.127 -> D: SPI semaphore not taken within 400 ticks by CPU 0, id hspec 19:29:21.127 -> D: Semaphore is claimed by stopsong 19:29:21.127 -> D: SPI semaphore not taken within 410 ticks by CPU 0, id hspec 19:29:21.127 -> D: Semaphore is claimed by stopsong 19:29:21.160 -> D: SPI semaphore not taken within 420 ticks by CPU 0, id hspec 19:29:21.160 -> D: Semaphore is claimed by stopsong 19:29:21.160 -> D: SPI semaphore not taken within 430 ticks by CPU 0, id hspec 19:29:21.160 -> D: Semaphore is claimed by stopsong 19:29:21.160 -> D: SPI semaphore not taken within 440 ticks by CPU 0, id hspec 19:29:21.160 -> D: Semaphore is claimed by stopsong 19:29:21.193 -> D: SPI semaphore not taken within 450 ticks by CPU 0, id hspec 19:29:21.193 -> D: Semaphore is claimed by stopsong 19:29:21.193 -> D: SPI semaphore not taken within 460 ticks by CPU 0, id hspec 19:29:21.193 -> D: Semaphore is claimed by stopsong 19:29:21.193 -> D: SPI semaphore not taken within 470 ticks by CPU 0, id hspec 19:29:21.193 -> D: Semaphore is claimed by stopsong 19:29:21.193 -> D: SPI semaphore not taken within 480 ticks by CPU 0, id hspec 19:29:21.193 -> D: Semaphore is claimed by stopsong 19:29:21.226 -> D: SPI semaphore not taken within 490 ticks by CPU 0, id hspec 19:29:21.226 -> D: Semaphore is claimed by stopsong 19:29:21.226 -> D: SPI semaphore not taken within 500 ticks by CPU 0, id hspec 19:29:21.226 -> D: Semaphore is claimed by stopsong 19:29:21.226 -> D: SPI semaphore not taken within 510 ticks by CPU 0, id hspec 19:29:21.226 -> D: Semaphore is claimed by stopsong 19:29:21.260 -> D: SPI semaphore not taken within 520 ticks by CPU 0, id hspec 19:29:21.260 -> D: Semaphore is claimed by stopsong 19:29:21.260 -> D: SPI semaphore not taken within 530 ticks by CPU 0, id hspec 19:29:21.260 -> D: Semaphore is claimed by stopsong 19:29:21.293 -> D: SPI semaphore not taken within 540 ticks by CPU 0, id hspec 19:29:21.293 -> D: Semaphore is claimed by stopsong 19:29:21.293 -> D: SPI semaphore not taken within 550 ticks by CPU 0, id hspec 19:29:21.293 -> D: Semaphore is claimed by stopsong 19:29:21.293 -> D: SPI semaphore not taken within 560 ticks by CPU 0, id hspec 19:29:21.293 -> D: Semaphore is claimed by stopsong 19:29:21.293 -> D: SPI semaphore not taken within 570 ticks by CPU 0, id hspec 19:29:21.293 -> D: Semaphore is claimed by stopsong 19:29:21.326 -> D: SPI semaphore not taken within 580 ticks by CPU 0, id hspec 19:29:21.326 -> D: Semaphore is claimed by stopsong 19:29:21.326 -> D: SPI semaphore not taken within 590 ticks by CPU 0, id hspec 19:29:21.326 -> D: Semaphore is claimed by stopsong 19:29:21.326 -> D: SPI semaphore not taken within 600 ticks by CPU 0, id hspec 19:29:21.326 -> D: Semaphore is claimed by stopsong 19:29:21.359 -> D: SPI semaphore not taken within 610 ticks by CPU 0, id hspec 19:29:21.359 -> D: Semaphore is claimed by stopsong 19:29:21.359 -> D: SPI semaphore not taken within 620 ticks by CPU 0, id hspec 19:29:21.359 -> D: Semaphore is claimed by stopsong 19:29:21.359 -> D: SPI semaphore not taken within 630 ticks by CPU 0, id hspec 19:29:21.359 -> D: Semaphore is claimed by stopsong 19:29:21.359 -> D: SPI semaphore not taken within 640 ticks by CPU 0, id hspec 19:29:21.359 -> D: Semaphore is claimed by stopsong 19:29:21.392 -> D: SPI semaphore not taken within 650 ticks by CPU 0, id hspec 19:29:21.392 -> D: Semaphore is claimed by stopsong 19:29:21.392 -> D: SPI semaphore not taken within 660 ticks by CPU 0, id hspec 19:29:21.392 -> D: Semaphore is claimed by stopsong 19:29:21.392 -> D: SPI semaphore not taken within 670 ticks by CPU 0, id hspec 19:29:21.392 -> D: Semaphore is claimed by stopsong 19:29:21.392 -> D: SPI semaphore not taken within 680 ticks by CPU 0, id hspec 19:29:21.392 -> D: Semaphore is claimed by stopsong 19:29:21.425 -> D: SPI semaphore not taken within 690 ticks by CPU 0, id hspec 19:29:21.425 -> D: Semaphore is claimed by stopsong 19:29:21.425 -> D: SPI semaphore not taken within 700 ticks by CPU 0, id hspec 19:29:21.425 -> D: Semaphore is claimed by stopsong 19:29:21.425 -> D: SPI semaphore not taken within 710 ticks by CPU 0, id hspec 19:29:21.425 -> D: Semaphore is claimed by stopsong 19:29:21.459 -> D: SPI semaphore not taken within 720 ticks by CPU 0, id hspec 19:29:21.459 -> D: Semaphore is claimed by stopsong 19:29:21.459 -> D: SPI semaphore not taken within 730 ticks by CPU 0, id hspec 19:29:21.459 -> D: Semaphore is claimed by stopsong 19:29:21.492 -> D: SPI semaphore not taken within 740 ticks by CPU 0, id hspec 19:29:21.492 -> D: Semaphore is claimed by stopsong 19:29:21.492 -> D: SPI semaphore not taken within 750 ticks by CPU 0, id hspec 19:29:21.492 -> D: Semaphore is claimed by stopsong 19:29:21.492 -> D: SPI semaphore not taken within 760 ticks by CPU 0, id hspec 19:29:21.492 -> D: Semaphore is claimed by stopsong 19:29:21.492 -> D: SPI semaphore not taken within 770 ticks by CPU 0, id hspec 19:29:21.492 -> D: Semaphore is claimed by stopsong 19:29:21.525 -> D: SPI semaphore not taken within 780 ticks by CPU 0, id hspec 19:29:21.525 -> D: Semaphore is claimed by stopsong 19:29:21.525 -> D: SPI semaphore not taken within 790 ticks by CPU 0, id hspec 19:29:21.525 -> D: Semaphore is claimed by stopsong 19:29:21.525 -> D: SPI semaphore not taken within 800 ticks by CPU 0, id hspec 19:29:21.525 -> D: Semaphore is claimed by stopsong 19:29:21.558 -> D: SPI semaphore not taken within 810 ticks by CPU 0, id hspec 19:29:21.558 -> D: Semaphore is claimed by stopsong 19:29:21.558 -> D: SPI semaphore not taken within 820 ticks by CPU 0, id hspec 19:29:21.558 -> D: Semaphore is claimed by stopsong 19:29:21.558 -> D: SPI semaphore not taken within 830 ticks by CPU 0, id hspec 19:29:21.558 -> D: Semaphore is claimed by stopsong 19:29:21.558 -> D: SPI semaphore not taken within 840 ticks by CPU 0, id hspec 19:29:21.558 -> D: Semaphore is claimed by stopsong 19:29:21.592 -> D: SPI semaphore not taken within 850 ticks by CPU 0, id hspec 19:29:21.592 -> D: Semaphore is claimed by stopsong 19:29:21.592 -> D: SPI semaphore not taken within 860 ticks by CPU 0, id hspec 19:29:21.592 -> D: Semaphore is claimed by stopsong 19:29:21.592 -> D: SPI semaphore not taken within 870 ticks by CPU 0, id hspec 19:29:21.592 -> D: Semaphore is claimed by stopsong 19:29:21.625 -> D: SPI semaphore not taken within 880 ticks by CPU 0, id hspec 19:29:21.625 -> D: Semaphore is claimed by stopsong 19:29:21.625 -> D: SPI semaphore not taken within 890 ticks by CPU 0, id hspec 19:29:21.625 -> D: Semaphore is claimed by stopsong 19:29:21.625 -> D: SPI semaphore not taken within 900 ticks by CPU 0, id hspec 19:29:21.625 -> D: Semaphore is claimed by stopsong 19:29:21.625 -> D: SPI semaphore not taken within 910 ticks by CPU 0, id hspec 19:29:21.625 -> D: Semaphore is claimed by stopsong 19:29:21.658 -> D: SPI semaphore not taken within 920 ticks by CPU 0, id hspec 19:29:21.658 -> D: Semaphore is claimed by stopsong 19:29:21.658 -> D: SPI semaphore not taken within 930 ticks by CPU 0, id hspec 19:29:21.658 -> D: Semaphore is claimed by stopsong 19:29:21.691 -> D: SPI semaphore not taken within 940 ticks by CPU 0, id hspec 19:29:21.691 -> D: Semaphore is claimed by stopsong 19:29:21.691 -> D: SPI semaphore not taken within 950 ticks by CPU 0, id hspec 19:29:21.691 -> D: Semaphore is claimed by stopsong 19:29:21.691 -> D: SPI semaphore not taken within 960 ticks by CPU 0, id hspec 19:29:21.691 -> D: Semaphore is claimed by stopsong 19:29:21.724 -> D: SPI semaphore not taken within 970 ticks by CPU 0, id hspec 19:29:21.724 -> D: Semaphore is claimed by stopsong 19:29:21.724 -> D: SPI semaphore not taken within 980 ticks by CPU 0, id hspec 19:29:21.724 -> D: Semaphore is claimed by stopsong 19:29:21.724 -> D: SPI semaphore not taken within 990 ticks by CPU 0, id hspec 19:29:21.724 -> D: Semaphore is claimed by stopsong 19:29:21.724 -> D: SPI semaphore not taken within 1000 ticks by CPU 0, id hspec 19:29:21.724 -> D: Semaphore is claimed by stopsong 19:29:22.654 -> D: Song stopped incorrectly! 19:29:22.654 -> D: REG Contents 19:29:22.654 -> D: --- ----- 19:29:22.654 -> D: 0 - 808 19:29:22.654 -> D: 1 - 33 19:29:22.654 -> D: 2 - 0 19:29:22.654 -> D: 3 - 6000 19:29:22.654 -> D: 4 - 0 19:29:22.654 -> D: 5 - AC45 19:29:22.687 -> D: 6 - 1800 19:29:22.687 -> D: 7 - 1E06 19:29:22.687 -> D: 8 - 0 19:29:22.687 -> D: 9 - 0 19:29:22.687 -> D: A - 0 19:29:22.687 -> D: B - 4646 19:29:22.687 -> D: C - 0 19:29:22.721 -> D: D - 0 19:29:22.721 -> D: E - 0 19:29:22.721 -> D: F - 0 19:29:38.860 -> D: Command client available 19:29:38.860 -> D: handlehttp started 19:29:38.860 -> D: FileRequest received config.html 19:29:38.860 -> D: Length of page is 4010 19:29:38.893 -> D: Response send 19:29:38.893 -> D: Command client available 19:29:38.893 -> D: handlehttp started 19:29:38.893 -> D: HTTP input shorter than expected 19:29:38.960 -> D: Command client available 19:29:38.960 -> D: handlehttp started 19:29:38.960 -> D: Filename is: radio.css 19:29:38.960 -> D: Start file reply for radio.css 19:29:38.960 -> D: FileRequest received radio.css 19:29:38.960 -> D: Length of page is 2032 19:29:38.960 -> D: Response send 19:29:38.960 -> D: Command client available 19:29:38.960 -> D: handlehttp started 19:29:38.993 -> D: Get command is: getnetworks 19:29:38.993 -> D: Send reply for getnetworks 19:29:38.993 -> D: Command: getnetworks with parameter 0 19:29:38.993 -> D: Command client available 19:29:38.993 -> D: handlehttp started 19:29:38.993 -> D: Get command is: getprefs 19:29:38.993 -> D: Send reply for getprefs 19:29:45.002 -> D: Command client available 19:29:45.002 -> D: handlehttp started 19:29:45.002 -> D: Filename is: index.html 19:29:45.002 -> D: Start file reply for index.html 19:29:45.002 -> D: FileRequest received index.html 19:29:45.002 -> D: Length of page is 8088 19:29:45.036 -> D: Response send 19:29:45.036 -> D: Command client available 19:29:45.036 -> D: handlehttp started 19:29:45.069 -> D: HTTP input shorter than expected 19:29:45.069 -> D: Command client available 19:29:45.069 -> D: handlehttp started 19:29:45.103 -> D: Get command is: settings 19:29:45.103 -> D: Send reply for settings 19:29:45.103 -> D: statstr is 0 - NAXI LOVE RADIO, Belgrade, Serbia 19:29:45.103 -> D: statstr is 1 - Easy Hits Florida 128k 19:29:45.103 -> D: statstr is 2 - CLASSIC ROCK MIA WWW.SHERADIO.COM 19:29:45.103 -> D: statstr is 3 - Magic Oldies Florida 19:29:45.103 -> D: statstr is 4 - Magic 60s Florida 60s Classic Rock 19:29:45.135 -> D: statstr is 5 - Classic Rock Florida - SHE Radio 19:29:45.135 -> D: statstr is 6 - Radio 1, NL 19:29:45.135 -> D: statstr is 7 - 1.FM - GAIA, 64k 19:29:45.135 -> D: statstr is 8 - Skonto 128k 19:29:45.135 -> D: statstr is 9 - *ILR CHILL and GROOVE 19:29:45.135 -> D: statstr is 10 - iHeartRadio IHR_IEDM 19:29:45.135 -> D: statstr is 11 - iHeartRadio IHR_TRAN 19:29:48.688 -> D: Command client available 19:29:48.688 -> D: handlehttp started 19:29:48.688 -> D: Get command is: preset=2 19:29:48.688 -> D: Send reply for preset=2 19:29:48.688 -> D: Command: preset with parameter 2 19:29:48.688 -> D: Command client available 19:29:48.688 -> D: handlehttp started 19:29:48.755 -> D: HTTP input shorter than expected 19:29:48.755 -> D: STOP requested 19:29:48.755 -> D: New preset/file requested (2/0) from us2.internet-radio.com:8050 19:29:48.755 -> D: Connect to new host us2.internet-radio.com:8050 19:29:48.755 -> D: Connect to us2.internet-radio.com on port 8050, extension / 19:29:48.954 -> D: SPI semaphore not taken within 120 ticks by CPU 1, id hspec 19:29:48.954 -> D: Semaphore is claimed by stopsong 19:29:48.954 -> D: SPI semaphore not taken within 130 ticks by CPU 0, id hspec 19:29:48.954 -> D: Semaphore is claimed by stopsong 19:29:48.954 -> D: SPI semaphore not taken within 140 ticks by CPU 0, id hspec 19:29:48.954 -> D: Semaphore is claimed by stopsong 19:29:48.987 -> D: SPI semaphore not taken within 150 ticks by CPU 0, id hspec 19:29:48.987 -> D: Semaphore is claimed by stopsong 19:29:48.987 -> D: SPI semaphore not taken within 160 ticks by CPU 0, id hspec 19:29:48.987 -> D: Semaphore is claimed by stopsong 19:29:48.987 -> D: SPI semaphore not taken within 170 ticks by CPU 0, id hspec 19:29:48.987 -> D: Semaphore is claimed by stopsong 19:29:49.020 -> D: SPI semaphore not taken within 180 ticks by CPU 0, id hspec 19:29:49.020 -> D: Semaphore is claimed by stopsong 19:29:49.020 -> D: SPI semaphore not taken within 190 ticks by CPU 0, id hspec 19:29:49.020 -> D: Semaphore is claimed by stopsong 19:29:49.054 -> D: SPI semaphore not taken within 200 ticks by CPU 0, id hspec 19:29:49.054 -> D: Semaphore is claimed by stopsong 19:29:49.054 -> D: SPI semaphore not taken within 210 ticks by CPU 0, id hspec 19:29:49.054 -> D: Semaphore is claimed by stopsong 19:29:49.054 -> D: SPI semaphore not taken within 220 ticks by CPU 0, id hspec 19:29:49.054 -> D: Semaphore is claimed by stopsong 19:29:49.054 -> D: SPI semaphore not taken within 230 ticks by CPU 0, id hspec 19:29:49.054 -> D: Semaphore is claimed by stopsong 19:29:49.054 -> D: SPI semaphore not taken within 240 ticks by CPU 0, id hspec 19:29:49.054 -> D: Semaphore is claimed by stopsong 19:29:49.087 -> D: SPI semaphore not taken within 250 ticks by CPU 0, id hspec 19:29:49.087 -> D: Semaphore is claimed by stopsong 19:29:49.087 -> D: SPI semaphore not taken within 260 ticks by CPU 0, id hspec 19:29:49.087 -> D: Semaphore is claimed by stopsong 19:29:49.087 -> D: Request us2.internet-radio.com:8050 failed! 19:29:49.087 -> D: SPI semaphore not taken within 270 ticks by CPU 0, id hspec 19:29:49.120 -> D: Semaphore is claimed by stopsong 19:29:49.120 -> D: SPI semaphore not taken within 280 ticks by CPU 0, id hspec 19:29:49.120 -> D: Semaphore is claimed by stopsong 19:29:49.120 -> D: Duration mp3loop 1 19:29:49.120 -> D: SPI semaphore not taken within 290 ticks by CPU 0, id hspec 19:29:49.120 -> D: Semaphore is claimed by stopsong 19:29:49.120 -> D: SPI semaphore not taken within 300 ticks by CPU 0, id hspec 19:29:49.120 -> D: Semaphore is claimed by stopsong 19:29:49.153 -> D: SPI semaphore not taken within 310 ticks by CPU 0, id hspec 19:29:49.153 -> D: Semaphore is claimed by stopsong 19:29:49.153 -> D: SPI semaphore not taken within 320 ticks by CPU 0, id hspec 19:29:49.153 -> D: Semaphore is claimed by stopsong 19:29:49.153 -> D: SPI semaphore not taken within 330 ticks by CPU 0, id hspec 19:29:49.153 -> D: Semaphore is claimed by stopsong 19:29:49.153 -> D: SPI semaphore not taken within 340 ticks by CPU 0, id hspec 19:29:49.153 -> D: Semaphore is claimed by stopsong 19:29:49.186 -> D: SPI semaphore not taken within 350 ticks by CPU 0, id hspec 19:29:49.186 -> D: Semaphore is claimed by stopsong 19:29:49.186 -> D: SPI semaphore not taken within 360 ticks by CPU 0, id hspec 19:29:49.186 -> D: Semaphore is claimed by stopsong 19:29:49.219 -> D: SPI semaphore not taken within 370 ticks by CPU 0, id hspec 19:29:49.219 -> D: Semaphore is claimed by stopsong 19:29:49.219 -> D: SPI semaphore not taken within 380 ticks by CPU 0, id hspec 19:29:49.219 -> D: Semaphore is claimed by stopsong 19:29:49.219 -> D: SPI semaphore not taken within 390 ticks by CPU 0, id hspec 19:29:49.219 -> D: Semaphore is claimed by stopsong 19:29:49.219 -> D: SPI semaphore not taken within 400 ticks by CPU 0, id hspec 19:29:49.219 -> D: Semaphore is claimed by stopsong 19:29:49.253 -> D: SPI semaphore not taken within 410 ticks by CPU 0, id hspec 19:29:49.253 -> D: Semaphore is claimed by stopsong 19:29:49.253 -> D: SPI semaphore not taken within 420 ticks by CPU 0, id hspec 19:29:49.253 -> D: Semaphore is claimed by stopsong 19:29:49.253 -> D: SPI semaphore not taken within 430 ticks by CPU 0, id hspec 19:29:49.253 -> D: Semaphore is claimed by stopsong 19:29:49.286 -> D: SPI semaphore not taken within 440 ticks by CPU 0, id hspec 19:29:49.286 -> D: Semaphore is claimed by stopsong 19:29:49.286 -> D: SPI semaphore not taken within 450 ticks by CPU 0, id hspec 19:29:49.286 -> D: Semaphore is claimed by stopsong 19:29:49.286 -> D: SPI semaphore not taken within 460 ticks by CPU 0, id hspec 19:29:49.286 -> D: Semaphore is claimed by stopsong 19:29:49.286 -> D: SPI semaphore not taken within 470 ticks by CPU 0, id hspec 19:29:49.286 -> D: Semaphore is claimed by stopsong 19:29:49.319 -> D: SPI semaphore not taken within 480 ticks by CPU 0, id hspec 19:29:49.319 -> D: Semaphore is claimed by stopsong 19:29:49.319 -> D: SPI semaphore not taken within 490 ticks by CPU 0, id hspec 19:29:49.319 -> D: Semaphore is claimed by stopsong 19:29:49.319 -> D: SPI semaphore not taken within 500 ticks by CPU 0, id hspec 19:29:49.319 -> D: Semaphore is claimed by stopsong 19:29:49.352 -> D: SPI semaphore not taken within 510 ticks by CPU 0, id hspec 19:29:49.352 -> D: Semaphore is claimed by stopsong 19:29:49.352 -> D: SPI semaphore not taken within 520 ticks by CPU 0, id hspec 19:29:49.352 -> D: Semaphore is claimed by stopsong 19:29:49.385 -> D: SPI semaphore not taken within 530 ticks by CPU 0, id hspec 19:29:49.385 -> D: Semaphore is claimed by stopsong 19:29:49.385 -> D: SPI semaphore not taken within 540 ticks by CPU 0, id hspec 19:29:49.385 -> D: Semaphore is claimed by stopsong 19:29:49.385 -> D: SPI semaphore not taken within 550 ticks by CPU 0, id hspec 19:29:49.385 -> D: Semaphore is claimed by stopsong 19:29:49.385 -> D: SPI semaphore not taken within 560 ticks by CPU 0, id hspec 19:29:49.385 -> D: Semaphore is claimed by stopsong 19:29:49.419 -> D: SPI semaphore not taken within 570 ticks by CPU 0, id hspec 19:29:49.419 -> D: Semaphore is claimed by stopsong 19:29:49.419 -> D: SPI semaphore not taken within 580 ticks by CPU 0, id hspec 19:29:49.419 -> D: Semaphore is claimed by stopsong 19:29:49.419 -> D: SPI semaphore not taken within 590 ticks by CPU 0, id hspec 19:29:49.419 -> D: Semaphore is claimed by stopsong 19:29:49.452 -> D: SPI semaphore not taken within 600 ticks by CPU 0, id hspec 19:29:49.452 -> D: Semaphore is claimed by stopsong 19:29:49.452 -> D: SPI semaphore not taken within 610 ticks by CPU 0, id hspec 19:29:49.452 -> D: Semaphore is claimed by stopsong 19:29:49.452 -> D: SPI semaphore not taken within 620 ticks by CPU 0, id hspec 19:29:49.452 -> D: Semaphore is claimed by stopsong 19:29:49.452 -> D: SPI semaphore not taken within 630 ticks by CPU 0, id hspec 19:29:49.452 -> D: Semaphore is claimed by stopsong 19:29:49.485 -> D: SPI semaphore not taken within 640 ticks by CPU 0, id hspec 19:29:49.485 -> D: Semaphore is claimed by stopsong 19:29:49.485 -> D: SPI semaphore not taken within 650 ticks by CPU 0, id hspec 19:29:49.485 -> D: Semaphore is claimed by stopsong 19:29:49.485 -> D: SPI semaphore not taken within 660 ticks by CPU 0, id hspec 19:29:49.485 -> D: Semaphore is claimed by stopsong 19:29:49.518 -> D: SPI semaphore not taken within 670 ticks by CPU 0, id hspec 19:29:49.518 -> D: Semaphore is claimed by stopsong 19:29:49.518 -> D: SPI semaphore not taken within 680 ticks by CPU 0, id hspec 19:29:49.518 -> D: Semaphore is claimed by stopsong 19:29:49.518 -> D: SPI semaphore not taken within 690 ticks by CPU 0, id hspec 19:29:49.518 -> D: Semaphore is claimed by stopsong 19:29:49.518 -> D: SPI semaphore not taken within 700 ticks by CPU 0, id hspec 19:29:49.518 -> D: Semaphore is claimed by stopsong 19:29:49.551 -> D: SPI semaphore not taken within 710 ticks by CPU 0, id hspec 19:29:49.551 -> D: Semaphore is claimed by stopsong 19:29:49.551 -> D: SPI semaphore not taken within 720 ticks by CPU 0, id hspec 19:29:49.551 -> D: Semaphore is claimed by stopsong 19:29:49.584 -> D: SPI semaphore not taken within 730 ticks by CPU 0, id hspec 19:29:49.584 -> D: Semaphore is claimed by stopsong 19:29:49.584 -> D: SPI semaphore not taken within 740 ticks by CPU 0, id hspec 19:29:49.584 -> D: Semaphore is claimed by stopsong 19:29:49.584 -> D: SPI semaphore not taken within 750 ticks by CPU 0, id hspec 19:29:49.584 -> D: Semaphore is claimed by stopsong 19:29:49.584 -> D: SPI semaphore not taken within 760 ticks by CPU 0, id hspec 19:29:49.584 -> D: Semaphore is claimed by stopsong 19:29:49.584 -> D: SPI semaphore not taken within 770 ticks by CPU 0, id hspec 19:29:49.584 -> D: Semaphore is claimed by stopsong 19:29:49.618 -> D: SPI semaphore not taken within 780 ticks by CPU 0, id hspec 19:29:49.618 -> D: Semaphore is claimed by stopsong 19:29:49.618 -> D: SPI semaphore not taken within 790 ticks by CPU 0, id hspec 19:29:49.618 -> D: Semaphore is claimed by stopsong 19:29:49.651 -> D: SPI semaphore not taken within 800 ticks by CPU 0, id hspec 19:29:49.651 -> D: Semaphore is claimed by stopsong 19:29:49.651 -> D: SPI semaphore not taken within 810 ticks by CPU 0, id hspec 19:29:49.651 -> D: Semaphore is claimed by stopsong 19:29:49.651 -> D: SPI semaphore not taken within 820 ticks by CPU 0, id hspec 19:29:49.651 -> D: Semaphore is claimed by stopsong 19:29:49.651 -> D: SPI semaphore not taken within 830 ticks by CPU 0, id hspec 19:29:49.651 -> D: Semaphore is claimed by stopsong 19:29:49.684 -> D: SPI semaphore not taken within 840 ticks by CPU 0, id hspec 19:29:49.684 -> D: Semaphore is claimed by stopsong 19:29:49.684 -> D: SPI semaphore not taken within 850 ticks by CPU 0, id hspec 19:29:49.684 -> D: Semaphore is claimed by stopsong 19:29:49.684 -> D: SPI semaphore not taken within 860 ticks by CPU 0, id hspec 19:29:49.684 -> D: Semaphore is claimed by stopsong 19:29:49.717 -> D: SPI semaphore not taken within 870 ticks by CPU 0, id hspec 19:29:49.717 -> D: Semaphore is claimed by stopsong 19:29:49.717 -> D: SPI semaphore not taken within 880 ticks by CPU 0, id hspec 19:29:49.717 -> D: Semaphore is claimed by stopsong 19:29:49.717 -> D: SPI semaphore not taken within 890 ticks by CPU 0, id hspec 19:29:49.717 -> D: Semaphore is claimed by stopsong 19:29:49.717 -> D: SPI semaphore not taken within 900 ticks by CPU 0, id hspec 19:29:49.717 -> D: Semaphore is claimed by stopsong 19:29:49.750 -> D: SPI semaphore not taken within 910 ticks by CPU 0, id hspec 19:29:49.750 -> D: Semaphore is claimed by stopsong 19:29:49.750 -> D: SPI semaphore not taken within 920 ticks by CPU 0, id hspec 19:29:49.750 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 930 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 940 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 950 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 960 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 970 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 980 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 990 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:49.906 -> D: SPI semaphore not taken within 1000 ticks by CPU 0, id hspec 19:29:49.906 -> D: Semaphore is claimed by stopsong 19:29:50.746 -> D: Song stopped incorrectly! 19:29:50.746 -> D: REG Contents 19:29:50.746 -> D: --- ----- 19:29:50.746 -> D: 0 - 808 19:29:50.746 -> D: 1 - 33 19:29:50.746 -> D: 2 - 0 19:29:50.746 -> D: 3 - 6000 19:29:50.779 -> D: 4 - 0 19:29:50.779 -> D: 5 - AC45 19:29:50.779 -> D: 6 - 1840 19:29:50.779 -> D: 7 - 1E06 19:29:50.779 -> D: 8 - 0 19:29:50.779 -> D: 9 - 0 19:29:50.779 -> D: A - 0 19:29:50.812 -> D: B - 4646 19:29:50.812 -> D: C - 0 19:29:50.812 -> D: D - 0 19:29:50.812 -> D: E - 0 19:29:50.812 -> D: F - 0 19:30:09.639 -> D: STOP requested 19:30:09.639 -> D: New preset/file requested (3/0) from airspectrum.cdnstream1.com:8000/1261_192 19:30:09.639 -> D: Connect to new host airspectrum.cdnstream1.com:8000/1261_192 19:30:09.639 -> D: Connect to airspectrum.cdnstream1.com on port 8000, extension /1261_192 19:30:09.739 -> D: SPI semaphore not taken within 120 ticks by CPU 0, id hspec 19:30:09.739 -> D: Semaphore is claimed by stopsong 19:30:09.772 -> D: SPI semaphore not taken within 130 ticks by CPU 0, id hspec 19:30:09.772 -> D: Semaphore is claimed by stopsong 19:30:09.772 -> D: SPI semaphore not taken within 140 ticks by CPU 0, id hspec 19:30:09.772 -> D: Semaphore is claimed by stopsong 19:30:09.772 -> D: SPI semaphore not taken within 150 ticks by CPU 0, id hspec 19:30:09.772 -> D: Semaphore is claimed by stopsong 19:30:09.805 -> D: SPI semaphore not taken within 160 ticks by CPU 0, id hspec 19:30:09.805 -> D: Semaphore is claimed by stopsong 19:30:09.805 -> D: SPI semaphore not taken within 170 ticks by CPU 0, id hspec 19:30:09.805 -> D: Semaphore is claimed by stopsong 19:30:09.805 -> D: SPI semaphore not taken within 180 ticks by CPU 0, id hspec 19:30:09.805 -> D: Semaphore is claimed by stopsong 19:30:09.805 -> D: SPI semaphore not taken within 190 ticks by CPU 0, id hspec 19:30:09.805 -> D: Semaphore is claimed by stopsong 19:30:09.838 -> D: SPI semaphore not taken within 200 ticks by CPU 0, id hspec 19:30:09.838 -> D: Semaphore is claimed by stopsong 19:30:09.838 -> D: SPI semaphore not taken within 210 ticks by CPU 0, id hspec 19:30:09.838 -> D: Semaphore is claimed by stopsong 19:30:09.838 -> D: SPI semaphore not taken within 220 ticks by CPU 0, id hspec 19:30:09.838 -> D: Semaphore is claimed by stopsong 19:30:09.871 -> D: SPI semaphore not taken within 230 ticks by CPU 0, id hspec 19:30:09.871 -> D: Semaphore is claimed by stopsong 19:30:09.871 -> D: SPI semaphore not taken within 240 ticks by CPU 0, id hspec 19:30:09.871 -> D: Semaphore is claimed by stopsong 19:30:09.905 -> D: SPI semaphore not taken within 250 ticks by CPU 0, id hspec 19:30:09.905 -> D: Semaphore is claimed by stopsong 19:30:09.905 -> D: SPI semaphore not taken within 260 ticks by CPU 0, id hspec 19:30:09.905 -> D: Semaphore is claimed by stopsong 19:30:09.905 -> D: SPI semaphore not taken within 270 ticks by CPU 0, id hspec 19:30:09.905 -> D: Semaphore is claimed by stopsong 19:30:09.905 -> D: SPI semaphore not taken within 280 ticks by CPU 0, id hspec 19:30:09.905 -> D: Semaphore is claimed by stopsong 19:30:09.938 -> D: SPI semaphore not taken within 290 ticks by CPU 0, id hspec 19:30:09.938 -> D: Semaphore is claimed by stopsong 19:30:09.938 -> D: SPI semaphore not taken within 300 ticks by CPU 0, id hspec 19:30:09.938 -> D: Semaphore is claimed by stopsong 19:30:09.938 -> D: SPI semaphore not taken within 310 ticks by CPU 0, id hspec 19:30:09.938 -> D: Semaphore is claimed by stopsong 19:30:09.971 -> D: SPI semaphore not taken within 320 ticks by CPU 0, id hspec 19:30:09.971 -> D: Semaphore is claimed by stopsong 19:30:09.971 -> D: SPI semaphore not taken within 330 ticks by CPU 0, id hspec 19:30:09.971 -> D: Semaphore is claimed by stopsong 19:30:09.971 -> D: SPI semaphore not taken within 340 ticks by CPU 0, id hspec 19:30:09.971 -> D: Semaphore is claimed by stopsong 19:30:09.971 -> D: SPI semaphore not taken within 350 ticks by CPU 0, id hspec 19:30:09.971 -> D: Semaphore is claimed by stopsong 19:30:09.971 -> D: Connected to server 19:30:09.971 -> D: SPI semaphore not taken within 360 ticks by CPU 0, id hspec 19:30:10.004 -> D: Semaphore is claimed by stopsong 19:30:10.004 -> D: SPI semaphore not taken within 370 ticks by CPU 0, id hspec 19:30:10.004 -> D: Semaphore is claimed by stopsong 19:30:10.004 -> D: SPI semaphore not taken within 380 ticks by CPU 0, id hspec 19:30:10.004 -> D: Semaphore is claimed by stopsong 19:30:10.004 -> D: SPI semaphore not taken within 390 ticks by CPU 0, id hspec 19:30:10.004 -> D: Semaphore is claimed by stopsong 19:30:10.038 -> D: SPI semaphore not taken within 400 ticks by CPU 0, id hspec 19:30:10.038 -> D: Semaphore is claimed by stopsong 19:30:10.038 -> D: SPI semaphore not taken within 410 ticks by CPU 0, id hspec 19:30:10.038 -> D: Semaphore is claimed by stopsong 19:30:10.038 -> D: SPI semaphore not taken within 420 ticks by CPU 0, id hspec 19:30:10.038 -> D: Semaphore is claimed by stopsong 19:30:10.071 -> D: SPI semaphore not taken within 430 ticks by CPU 0, id hspec 19:30:10.071 -> D: Semaphore is claimed by stopsong 19:30:10.071 -> D: SPI semaphore not taken within 440 ticks by CPU 0, id hspec 19:30:10.071 -> D: Semaphore is claimed by stopsong 19:30:10.104 -> D: SPI semaphore not taken within 450 ticks by CPU 0, id hspec 19:30:10.104 -> D: Semaphore is claimed by stopsong 19:30:10.104 -> D: SPI semaphore not taken within 460 ticks by CPU 0, id hspec 19:30:10.104 -> D: Semaphore is claimed by stopsong 19:30:10.104 -> D: SPI semaphore not taken within 470 ticks by CPU 0, id hspec 19:30:10.104 -> D: Semaphore is claimed by stopsong 19:30:10.104 -> D: SPI semaphore not taken within 480 ticks by CPU 0, id hspec 19:30:10.104 -> D: Semaphore is claimed by stopsong 19:30:10.104 -> D: SPI semaphore not taken within 490 ticks by CPU 0, id hspec 19:30:10.104 -> D: Semaphore is claimed by stopsong 19:30:10.137 -> D: SPI semaphore not taken within 500 ticks by CPU 0, id hspec 19:30:10.137 -> D: Semaphore is claimed by stopsong 19:30:10.137 -> D: SPI semaphore not taken within 510 ticks by CPU 0, id hspec 19:30:10.137 -> D: Semaphore is claimed by stopsong 19:30:10.170 -> D: SPI semaphore not taken within 520 ticks by CPU 0, id hspec 19:30:10.170 -> D: Semaphore is claimed by stopsong 19:30:10.170 -> D: SPI semaphore not taken within 530 ticks by CPU 0, id hspec 19:30:10.170 -> D: Semaphore is claimed by stopsong 19:30:10.170 -> D: SPI semaphore not taken within 540 ticks by CPU 0, id hspec 19:30:10.170 -> D: Semaphore is claimed by stopsong 19:30:10.170 -> D: SPI semaphore not taken within 550 ticks by CPU 0, id hspec 19:30:10.170 -> D: Semaphore is claimed by stopsong 19:30:10.204 -> D: SPI semaphore not taken within 560 ticks by CPU 0, id hspec 19:30:10.204 -> D: Semaphore is claimed by stopsong 19:30:10.204 -> D: SPI semaphore not taken within 570 ticks by CPU 0, id hspec 19:30:10.204 -> D: Semaphore is claimed by stopsong 19:30:10.204 -> D: SPI semaphore not taken within 580 ticks by CPU 0, id hspec 19:30:10.204 -> D: Semaphore is claimed by stopsong 19:30:10.237 -> D: SPI semaphore not taken within 590 ticks by CPU 0, id hspec 19:30:10.237 -> D: Semaphore is claimed by stopsong 19:30:10.237 -> D: SPI semaphore not taken within 600 ticks by CPU 0, id hspec 19:30:10.237 -> D: Semaphore is claimed by stopsong 19:30:10.237 -> D: SPI semaphore not taken within 610 ticks by CPU 0, id hspec 19:30:10.237 -> D: Semaphore is claimed by stopsong 19:30:10.237 -> D: SPI semaphore not taken within 620 ticks by CPU 0, id hspec 19:30:10.237 -> D: Semaphore is claimed by stopsong 19:30:10.270 -> D: SPI semaphore not taken within 630 ticks by CPU 0, id hspec 19:30:10.270 -> D: Semaphore is claimed by stopsong 19:30:10.270 -> D: SPI semaphore not taken within 640 ticks by CPU 0, id hspec 19:30:10.270 -> D: Semaphore is claimed by stopsong 19:30:10.303 -> D: SPI semaphore not taken within 650 ticks by CPU 0, id hspec 19:30:10.303 -> D: Semaphore is claimed by stopsong 19:30:10.303 -> D: SPI semaphore not taken within 660 ticks by CPU 0, id hspec 19:30:10.303 -> D: Semaphore is claimed by stopsong 19:30:10.303 -> D: SPI semaphore not taken within 670 ticks by CPU 0, id hspec 19:30:10.303 -> D: Semaphore is claimed by stopsong 19:30:10.303 -> D: SPI semaphore not taken within 680 ticks by CPU 0, id hspec 19:30:10.303 -> D: Semaphore is claimed by stopsong 19:30:10.303 -> D: SPI semaphore not taken within 690 ticks by CPU 0, id hspec 19:30:10.303 -> D: Semaphore is claimed by stopsong 19:30:10.336 -> D: SPI semaphore not taken within 700 ticks by CPU 0, id hspec 19:30:10.336 -> D: Semaphore is claimed by stopsong 19:30:10.336 -> D: SPI semaphore not taken within 710 ticks by CPU 0, id hspec 19:30:10.336 -> D: Semaphore is claimed by stopsong 19:30:10.369 -> D: SPI semaphore not taken within 720 ticks by CPU 0, id hspec 19:30:10.369 -> D: Semaphore is claimed by stopsong 19:30:10.369 -> D: SPI semaphore not taken within 730 ticks by CPU 0, id hspec 19:30:10.369 -> D: Semaphore is claimed by stopsong 19:30:10.369 -> D: SPI semaphore not taken within 740 ticks by CPU 0, id hspec 19:30:10.369 -> D: Semaphore is claimed by stopsong 19:30:10.369 -> D: SPI semaphore not taken within 750 ticks by CPU 0, id hspec 19:30:10.369 -> D: Semaphore is claimed by stopsong 19:30:10.403 -> D: SPI semaphore not taken within 760 ticks by CPU 1, id hspec 19:30:10.403 -> D: Semaphore is claimed by stopsong 19:30:10.403 -> D: Switch to HEADER 19:30:10.403 -> D: Headerline: Content-Type: audio/mpeg 19:30:10.403 -> D: audio/mpeg seen. 19:30:10.403 -> D: Headerline: icy-br:192 19:30:10.403 -> D: Headerline: ice-a taken within 770 ticks by CPU 0, id hspec=192;ice-channels=2D: SPI semaphore not taken within 770 ticks by CPU 0, id hspec 19:30:10.436 -> 19:30:10.436 -> D: D: Headerline: icy-br:192Headerline: icy-br:192 19:30:10.436 -> D: Headerline: icy-description:Magic Oldies Florida HD 192 Kbps with the Best Oldies on the Planet! 19:30:10.436 -> 19:30:10.436 -> D: Headerline: icy-genre:Oldies 60s, 70s, 80s 19:30:10.436 -> D: Headerline: icy-name:Magic Oldies Florida 19:30:10.436 -> D: Headerline: i not taken wD: 19:30:10.436 -> D: Headerline: icy-pub:1Headerline: icy-pub:1 19:30:10.436 -> D: Headerline: icy-url:http://www.magicoldiesflorida.com 19:30:10.436 -> D: Semaphore is claimed by stopsong 19:30:10.469 -> 19:30:10.469 -> D: Headerline: Server: Icecast 2.4.0-kh4 19:30:10.469 -> D: Headerline: Cache-Control: no-cache, no-store 19:30:10.469 -> D: Headerline: Access-aken within 790 ticks bD: SPI semaphore not taken within 790 ticks by CPU 0, id hspec 19:30:10.469 -> D: Headerline: Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type 19:30:10.502 -> D: Semaphore is claimed by stopsong 19:30:10.502 -> 19:30:10.502 -> D: l-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type seen. 19:30:10.502 -> D: Headerline: Access-Control-Allow-Methoks by CPU 0, id hspecD: SPI semaphore not taken within 800 ticks by CPU 0, id hspec 19:30:10.502 -> 19:30:10.502 -> D: Semaerline: Connection: ClosengD: Headerline: Connection: Close 19:30:10.502 -> 19:30:10.502 -> D: Headerline: Expires: Mon, 26 Jul 1997 05:00:00 GMT 19:30:10.502 -> D: Headerline: icy-metaint:16000 19:30:10.502 -> D: Switch to DATAnot taken within 810 ticks by CPU D: SPI semaphore not taken within 810 ticks by CPU 0, id hspec 19:30:10.536 -> D: Duration mp3loop 128 19:30:10.536 -> D: Semaphore is claimed by stopsong 19:30:10.536 -> 19:30:10.536 -> D: SPI semaphore not taken within 820 ticks by CPU 0, id hspec 19:30:10.536 -> D: Semaphore is claimed by stopsong 19:30:10.569 -> D: SPI semaphore not taken within 830 ticks by CPU 0, id hspec 19:30:10.569 -> D: Semaphore is claimed by stopsong 19:30:10.569 -> D: SPI semaphore not taken within 840 ticks by CPU 0, id hspec 19:30:10.569 -> D: Semaphore is claimed by stopsong 19:30:10.569 -> D: SPI semaphore not taken within 850 ticks by CPU 0, id hspec 19:30:10.569 -> D: Semaphore is claimed by stopsong 19:30:10.569 -> D: SPI semaphore not taken within 860 ticks by CPU 0, id hspec 19:30:10.569 -> D: Semaphore is claimed by stopsong 19:30:10.602 -> D: SPI semaphore not taken within 870 ticks by CPU 0, id hspec 19:30:10.602 -> D: Semaphore is claimed by stopsong 19:30:10.602 -> D: SPI semaphore not taken within 880 ticks by CPU 0, id hspec 19:30:10.602 -> D: Semaphore is claimed by stopsong 19:30:10.602 -> D: SPI semaphore not taken within 890 ticks by CPU 0, id hspec 19:30:10.602 -> D: Semaphore is claimed by stopsong 19:30:10.602 -> D: SPI semaphore not taken within 900 ticks by CPU 0, id hspec 19:30:10.602 -> D: Semaphore is claimed by stopsong 19:30:10.635 -> D: SPI semaphore not taken within 910 ticks by CPU 0, id hspec 19:30:10.635 -> D: Semaphore is claimed by stopsong 19:30:10.635 -> D: SPI semaphore not taken within 920 ticks by CPU 0, id hspec 19:30:10.635 -> D: Semaphore is claimed by stopsong 19:30:10.635 -> D: SPI semaphore not taken within 930 ticks by CPU 0, id hspec 19:30:10.635 -> D: Semaphore is claimed by stopsong 19:30:10.669 -> D: SPI semaphore not taken within 940 ticks by CPU 0, id hspec 19:30:10.669 -> D: Semaphore is claimed by stopsong 19:30:10.669 -> D: SPI semaphore not taken within 950 ticks by CPU 0, id hspec 19:30:10.669 -> D: Semaphore is claimed by stopsong 19:30:10.669 -> D: SPI semaphore not taken within 960 ticks by CPU 0, id hspec 19:30:10.669 -> D: Semaphore is claimed by stopsong 19:30:10.669 -> D: SPI semaphore not taken within 970 ticks by CPU 0, id hspec 19:30:10.669 -> D: Semaphore is claimed by stopsong 19:30:10.702 -> D: SPI semaphore not taken within 980 ticks by CPU 0, id hspec 19:30:10.702 -> D: Semaphore is claimed by stopsong 19:30:10.702 -> D: SPI semaphore not taken within 990 ticks by CPU 0, id hspec 19:30:10.702 -> D: Semaphore is claimed by stopsong 19:30:10.702 -> D: SPI semaphore not taken within 1000 ticks by CPU 0, id hspec 19:30:10.702 -> D: Semaphore is claimed by stopsong 19:30:11.599 -> D: Duration mp3loop 401 19:30:11.632 -> D: Song stopped incorrectly! 19:30:11.632 -> D: REG Contents 19:30:11.632 -> D: --- ----- 19:30:11.632 -> D: 0 - 808 19:30:11.632 -> D: 1 - 33 19:30:11.665 -> D: 2 - 0 19:30:11.665 -> D: 3 - 6000 19:30:11.665 -> D: 4 - 0 19:30:11.665 -> D: 5 - AC45 19:30:11.665 -> D: 6 - 1800 19:30:11.665 -> D: 7 - 1E06 19:30:11.698 -> D: 8 - 0 19:30:11.698 -> D: 9 - 0 19:30:11.698 -> D: A - 0 19:30:11.698 -> D: B - 4646 19:30:11.698 -> D: C - 0 19:30:11.698 -> D: D - 0 19:30:11.698 -> D: E - 0 19:30:11.731 -> D: F - 0 19:30:12.229 -> D: Metadata block 64 bytes 19:30:12.229 -> D: Streamtitle found, 50 bytes 19:30:12.229 -> D: StreamTitle='Jethro Tull - Bungle In The Jungle ';

Edzelf commented 4 years ago

This time the wifi connection fails. But we can see that the "stop song" function fails as well. I will look into this tomorrow.

minzukas commented 4 years ago

made new wiring, checked everything ten times. Now I also get VS1053 error in addition... stop song however is also the same.

23:36:17.802 -> ⸮r⸮l⸮|⸮$⸮⸮⸮⸮⸮⸮$⸮⸮l2$⸮r⸮bb⸮l⸮⸮⸮⸮lllr⸮⸮⸮lp⸮⸮&'⸮⸮⸮#$⸮$⸮⸮⸮⸮l⸮⸮l~⸮l⸮lrl⸮nn⸮⸮rnbb⸮⸮l⸮⸮⸮⸮⸮ ⸮';⸮⸮"⸮"#⸮⸮⸮"⸮##⸮⸮<⸮"#8⸮⸮⸮<⸮##'⸮⸮<⸮##⸮⸮⸮<⸮#$ ;⸮'⸮⸮⸮⸮⸮~⸮b⸮nnpn⸮⸮bllp⸮n⸮br⸮⸮⸮⸮l#'⸮<"⸮$8⸮'⸮";⸮⸮⸮⸮$⸮⸮#'⸮<#$⸮⸮$8⸮'⸮"⸮⸮bn⸮|bl⸮⸮lp⸮n⸮#⸮⸮bn⸮|bl⸮l⸮⸮l⸮n⸮|⸮⸮⸮⸮⸮⸮ 23:36:18.090 -> D: Starting ESP32-radio running on CPU 1 at 240 MHz. Version Thu, 16 Dec 2019 09:07:00 GMT. Free memory 294276 23:36:18.090 -> D: Display type is DUMMYTFT 23:36:18.090 -> D: Partition nvs found, 20480 bytes 23:36:18.090 -> D: Read 43 keys from NVS 23:36:18.090 -> D: pin_ir set to 35 23:36:18.090 -> D: pin_enc_clk set to 25 23:36:18.090 -> D: pin_enc_dt set to 26 23:36:18.090 -> D: pin_enc_sw set to 27 23:36:18.090 -> D: pin_tft_cs set to 15 23:36:18.090 -> D: pin_tft_dc set to 2 23:36:18.090 -> D: pin_tft_scl set to -1 23:36:18.090 -> D: pin_tft_sda set to -1 23:36:18.090 -> D: pin_tft_bl set to -1 23:36:18.090 -> D: pin_tft_blx set to -1 23:36:18.090 -> D: pin_sd_cs set to 21 23:36:18.090 -> D: pin_vs_cs set to 5 23:36:18.090 -> D: pin_vs_dcs set to 16 23:36:18.090 -> D: pin_vs_dreq set to 4 23:36:18.090 -> D: pin_shutdown set to -1 23:36:18.090 -> D: pin_shutdownx set to -1 23:36:18.090 -> D: pin_spi_sck set to 18 23:36:18.090 -> D: pin_spi_miso set to 19 23:36:18.090 -> D: pin_spi_mosi set to 23 23:36:18.090 -> D: GPIO0 is HIGH 23:36:18.090 -> D: GPIO2 is LOW, probably no PULL-UP 23:36:18.090 -> D: GPIO4 is HIGH 23:36:18.090 -> D: GPIO5 is HIGH 23:36:18.090 -> D: GPIO12 is HIGH 23:36:18.090 -> D: GPIO13 is HIGH 23:36:18.090 -> D: GPIO14 is HIGH 23:36:18.090 -> D: GPIO15 is HIGH 23:36:18.090 -> D: GPIO16 is HIGH 23:36:18.090 -> D: GPIO17 is HIGH 23:36:18.090 -> D: GPIO18 is HIGH 23:36:18.090 -> D: GPIO19 is HIGH 23:36:18.090 -> D: GPIO21 is HIGH 23:36:18.090 -> D: GPIO22 is HIGH 23:36:18.102 -> D: GPIO23 is HIGH 23:36:18.102 -> D: GPIO25 is HIGH 23:36:18.102 -> D: GPIO26 is HIGH 23:36:18.102 -> D: GPIO27 is HIGH 23:36:18.102 -> D: GPIO32 is HIGH 23:36:18.102 -> D: GPIO33 is HIGH 23:36:18.102 -> D: GPIO34 is LOW, probably no PULL-UP 23:36:18.102 -> D: GPIO35 is LOW, probably no PULL-UP 23:36:18.102 -> D: GPIO39 is LOW, probably no PULL-UP 23:36:18.102 -> D: gpio_00 will execute uppreset = 1 23:36:18.102 -> D: gpio_12 will execute upvolume = 2 23:36:18.102 -> D: gpio_13 will execute downvolume = 2 23:36:18.102 -> D: Enable pin 35 for IR 23:36:18.102 -> D: Start display 23:36:18.509 -> D: SD Card Mount Failed! 23:36:18.509 -> D: Create list with acceptable WiFi networks 23:36:18.509 -> D: Added NETGEAR35 to list of networks 23:36:18.509 -> D: Added SSID2 to list of networks 23:36:18.509 -> D: End adding networks 23:36:18.675 -> D: Scan Networks 23:36:20.811 -> D: Scan completed 23:36:20.811 -> D: Number of available networks: 7 23:36:20.811 -> D: 1 - NETGEAR35 Signal: -77 dBm, Encryption WPA2_PSK, Acceptable 23:36:20.811 -> D: 2 - Balticum12-2 Signal: -79 dBm, Encryption WPA_WPA2_PSK, 23:36:20.811 -> D: 3 - Rubuilijus Signal: -81 dBm, Encryption WPA_WPA2_PSK, 23:36:20.811 -> D: 4 - PS4-B6645C2DBBC4 Signal: -82 dBm, Encryption WPA2_PSK, 23:36:20.811 -> D: 5 - Balticum 12-15 Signal: -84 dBm, Encryption WPA_WPA2_PSK, 23:36:20.811 -> D: 6 - Telia-CA71C9-Greitas Signal: -85 dBm, Encryption WPA2_PSK, 23:36:20.934 -> D: 7 - Odeta Signal: -94 dBm, Encryption WPA2_PSK, 23:36:20.934 -> D: End of list 23:36:20.934 -> D: Command: clk_dst with parameter 1 23:36:20.934 -> D: Command: clk_offset with parameter 1 23:36:20.934 -> D: Command: clk_server with parameter pool.ntp.org 23:36:20.934 -> D: Command: gpio_00 with parameter uppreset = 1 23:36:20.934 -> D: Command: gpio_12 with parameter upvolume = 2 23:36:20.934 -> D: Command: gpio_13 with parameter downvolume = 2 23:36:20.934 -> D: Command: ir_40bf with parameter upvolume = 2 23:36:20.934 -> D: Command: ir_c03f with parameter downvolume = 2 23:36:20.934 -> D: Command: mqttbroker with parameter none 23:36:20.934 -> D: Command: mqttpasswd with parameter *** 23:36:20.934 -> D: Command: mqttport with parameter 1883 23:36:20.934 -> D: Command: mqttprefix with parameter none 23:36:20.934 -> D: Command: mqttuser with parameter none 23:36:20.934 -> D: Command: pin_enc_clk with parameter 25 23:36:20.934 -> D: Command: pin_enc_dt with parameter 26 23:36:20.934 -> D: Command: pin_enc_sw with parameter 27 23:36:20.934 -> D: Command: pin_ir with parameter 35 23:36:20.934 -> D: Command: pin_sd_cs with parameter 21 23:36:20.934 -> D: Command: pin_tft_cs with parameter 15 23:36:20.934 -> D: Command: pin_tft_dc with parameter 2 23:36:20.934 -> D: Command: pin_vs_cs with parameter 5 23:36:20.934 -> D: Command: pin_vs_dcs with parameter 16 23:36:20.934 -> D: Command: pin_vs_dreq with parameter 4 23:36:20.934 -> D: Command: preset with parameter 6 23:36:20.934 -> D: Command: preset_00 with parameter 109.206.96.34:8100 23:36:20.934 -> D: Command: preset_01 with parameter airspectrum.cdnstream1.com:8114/1648_128 23:36:20.960 -> D: Command: preset_02 with parameter us2.internet-radio.com:8050 23:36:20.960 -> D: Command: preset_03 with parameter airspectrum.cdnstream1.com:8000/1261_192 23:36:20.960 -> D: Command: preset_04 with parameter airspectrum.cdnstream1.com:8008/1604_128 23:36:20.960 -> D: Command: preset_05 with parameter us1.internet-radio.com:8105 23:36:20.960 -> D: Command: preset_06 with parameter icecast.omroep.nl:80/radio1-bb-mp3 23:36:20.960 -> D: Command: preset_07 with parameter 205.164.62.15:10032 23:36:20.960 -> D: Command: preset_08 with parameter skonto.ls.lv:8002/mp3 23:36:20.960 -> D: Command: preset_09 with parameter 94.23.66.155:8106 23:36:20.982 -> D: Command: preset_10 with parameter ihr/IHR_IEDM 23:36:20.982 -> D: Command: preset_11 with parameter ihr/IHR_TRAN 23:36:20.982 -> D: Command: toneha with parameter 0 23:36:20.982 -> D: Command: tonehf with parameter 0 23:36:20.982 -> D: Command: tonela with parameter 0 23:36:20.982 -> D: Command: tonelf with parameter 0 23:36:21.010 -> D: Command: volume with parameter 72 23:36:21.106 -> D: Slow SPI, Testing VS1053 read/write registers... 23:36:21.242 -> D: VS1053 SPI error. SB:A410 R1:C000 R2:A410 23:36:21.275 -> D: VS1053 SPI error. SB:E808 R1:E808 R2:E200 23:36:21.308 -> D: Connect to WiFi 23:36:24.062 -> D: Connected to NETGEAR35 23:36:24.062 -> D: IP = 192.168.1.13 23:36:27.047 -> D: Start server for commands 23:36:27.047 -> D: Network found. Starting mqtt and OTA 23:36:27.047 -> D: MDNS responder started 23:36:28.076 -> D: Rotary encoder is enabled 23:36:28.076 -> D: STOP requested 23:36:28.076 -> D: New preset/file requested (6/0) from icecast.omroep.nl:80/radio1-bb-mp3 23:36:28.076 -> D: Connect to new host icecast.omroep.nl:80/radio1-bb-mp3 23:36:28.076 -> D: Connect to icecast.omroep.nl on port 80, extension /radio1-bb-mp3 23:36:28.109 -> D: Connected to server 23:36:28.109 -> D: Duration mp3loop 1 23:36:28.175 -> D: Switch to HEADER 23:36:28.175 -> D: Headerline: Content-Type: audio/mpeg 23:36:28.175 -> D: audio/mpeg seen. 23:36:28.175 -> D: Headerline: icy-br:192 23:36:28.175 -> D: Headerline: ice-audio-info: samplerate=48000;channels=2;bitrate=192 23:36:28.175 -> D: Headerline: icy-br:192 23:36:28.175 -> D: Headerline: icy-g taken wiD: SPI semaphore not taken within 120 ticks by CPU 0, id hspec 23:36:28.208 -> 23:36:28.208 -> D: Semaprline: icy-metadata:1psongD: Headerline: icy-metadata:1 23:36:28.208 -> 23:36:28.208 -> D: Headerline: icy-name:NPO Radio1 23:36:28.208 -> D: Headerline: icy-pub:0 23:36:28.208 -> D: Headerline: icy-url:http://www.radio1.nl 23:36:28.208 -> D: SPI semaphore not taken within 130 ticD: SPI semaphore not taken within 130 ticks by CPU 0, id hspec 23:36:28.241 -> 23:36:28.241 -> D: D: Semaphore is claimed by stopsongSemaphore is claimed by stopsong 23:36:28.241 -> 23:36:28.241 -> D: Headerline: Access-Control-Allow-Origin: * 23:36:28.241 -> D: Headerline: Access-Control-Allow-Headers: Origin, Accept, X-Requested-With, Content-TypeD: SPI semaphore not taken within 140 ticks by CPU 1, id hspec 23:36:28.241 -> 23:36:28.241 -> D: l-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type seen.D: l-Allow-Headers: Origin, Accept, X-Requested-With, Content-Type seen. 23:36:28.241 -> 23:36:28.241 -> D: Headerline: Access-Control-Allow-Methods: GET, OPTIONS, HEAD 23:36:28.275 -> D: Headerline: Connection: Close 23:36:28.275 -> D: HeI semaphore not taken within 150 ticks by CPU 0,D: 23:36:28.275 -> SPaderline: icy-metaint:16000 150 ticks by CPU 0, id hspec 23:36:28.275 -> D: D: Semaphore is claimed by stopsongSemaphore is claimed by stopsong 23:36:28.308 -> 23:36:28.308 -> D: Switch to DATA, bitrate is 192, metaint is 16000 23:36:28.308 -> D: Duration mp3loop 109 23:36:28.308 -> D: SPI semaphore not taken within 160 ticks by CPU 1, id hspec 23:36:28.308 -> D: Semaphore is claimed by stopsong 23:36:28.308 -> D: SPI semaphore not taken within 170 ticks by CPU 0, id hspec 23:36:28.308 -> D: Semaphore is claimed by stopsong 23:36:28.308 -> D: SPI semaphore not taken within 180 ticks by CPU 1, id hspec 23:36:28.308 -> D: Semaphore is claimed by stopsong 23:36:28.341 -> D: SPI semaphore not taken within 190 ticks by CPU 1, id hspec 23:36:28.341 -> D: Semaphore is claimed by stopsong 23:36:28.341 -> D: SPI semaphore not taken within 200 ticks by CPU 0, id hspec 23:36:28.341 -> D: Semaphore is claimed by stopsong 23:36:28.341 -> D: SPI semaphore not taken within 210 ticks by CPU 0, id hspec 23:36:28.341 -> D: Semaphore is claimed by stopsong 23:36:28.374 -> D: SPI semaphore not taken within 220 ticks by CPU 1, id hspec 23:36:28.374 -> D: Semaphore is claimed by stopsong 23:36:28.374 -> D: SPI semaphore not taken within 230 ticks by CPU 0, id hspec 23:36:28.374 -> D: Semaphore is claimed by stopsong 23:36:28.374 -> D: SPI semaphore not taken within 240 ticks by CPU 0, id hspec 23:36:28.374 -> D: Semaphore is claimed by stopsong 23:36:28.374 -> D: SPI semaphore not taken within 250 ticks by CPU 0, id hspec 23:36:28.374 -> D: Semaphore is claimed by stopsong 23:36:28.407 -> D: SPI semaphore not taken within 260 ticks by CPU 1, id hspec 23:36:28.407 -> D: Semaphore is claimed by stopsong 23:36:28.407 -> D: SPI semaphore not taken within 270 ticks by CPU 1, id hspec 23:36:28.407 -> D: Semaphore is claimed by stopsong 23:36:28.407 -> D: SPI semaphore not taken within 280 ticks by CPU 0, id hspec 23:36:28.407 -> D: Semaphore is claimed by stopsong 23:36:28.407 -> D: SPI semaphore not taken within 290 ticks by CPU 0, id hspec 23:36:28.407 -> D: Semaphore is claimed by stopsong 23:36:28.440 -> D: SPI semaphore not taken within 300 ticks by CPU 0, id hspec 23:36:28.440 -> D: Semaphore is claimed by stopsong 23:36:28.440 -> D: SPI semaphore not taken within 310 ticks by CPU 0, id hspec 23:36:28.440 -> D: Semaphore is claimed by stopsong 23:36:28.474 -> D: SPI semaphore not taken within 320 ticks by CPU 0, id hspec 23:36:28.474 -> D: Semaphore is claimed by stopsong 23:36:28.474 -> D: SPI semaphore not taken within 330 ticks by CPU 0, id hspec 23:36:28.474 -> D: Semaphore is claimed by stopsong 23:36:28.474 -> D: SPI semaphore not taken within 340 ticks by CPU 0, id hspec 23:36:28.474 -> D: Semaphore is claimed by stopsong 23:36:28.474 -> D: SPI semaphore not taken within 350 ticks by CPU 0, id hspec 23:36:28.474 -> D: Semaphore is claimed by stopsong 23:36:28.474 -> D: SPI semaphore not taken within 360 ticks by CPU 1, id hspec 23:36:28.474 -> D: Semaphore is claimed by stopsong 23:36:28.507 -> D: SPI semaphore not taken within 370 ticks by CPU 1, id hspec 23:36:28.507 -> D: Semaphore is claimed by stopsong 23:36:28.507 -> D: SPI semaphore not taken within 380 ticks by CPU 0, id hspec 23:36:28.507 -> D: Semaphore is claimed by stopsong 23:36:28.540 -> D: SPI semaphore not taken within 390 ticks by CPU 0, id hspec 23:36:28.540 -> D: Semaphore is claimed by stopsong 23:36:28.540 -> D: SPI semaphore not taken within 400 ticks by CPU 0, id hspec 23:36:28.540 -> D: Semaphore is claimed by stopsong 23:36:28.540 -> D: SPI semaphore not taken within 410 ticks by CPU 0, id hspec 23:36:28.540 -> D: Semaphore is claimed by stopsong 23:36:28.540 -> D: SPI semaphore not taken within 420 ticks by CPU 0, id hspec 23:36:28.540 -> D: Semaphore is claimed by stopsong 23:36:28.573 -> D: SPI semaphore not taken within 430 ticks by CPU 0, id hspec 23:36:28.573 -> D: Semaphore is claimed by stopsong 23:36:28.573 -> D: SPI semaphore not taken within 440 ticks by CPU 0, id hspec 23:36:28.573 -> D: Semaphore is claimed by stopsong 23:36:28.573 -> D: SPI semaphore not taken within 450 ticks by CPU 0, id hspec 23:36:28.573 -> D: Semaphore is claimed by stopsong 23:36:28.606 -> D: SPI semaphore not taken within 460 ticks by CPU 0, id hspec 23:36:28.606 -> D: Semaphore is claimed by stopsong 23:36:28.606 -> D: SPI semaphore not taken within 470 ticks by CPU 1, id hspec 23:36:28.606 -> D: Semaphore is claimed by stopsong 23:36:28.606 -> D: SPI semaphore not taken within 480 ticks by CPU 1, id hspec 23:36:28.606 -> D: Semaphore is claimed by stopsong 23:36:28.606 -> D: SPI semaphore not taken within 490 ticks by CPU 0, id hspec 23:36:28.606 -> D: Semaphore is claimed by stopsong 23:36:28.640 -> D: SPI semaphore not taken within 500 ticks by CPU 0, id hspec 23:36:28.640 -> D: Semaphore is claimed by stopsong 23:36:28.640 -> D: SPI semaphore not taken within 510 ticks by CPU 0, id hspec 23:36:28.640 -> D: Semaphore is claimed by stopsong 23:36:28.640 -> D: SPI semaphore not taken within 520 ticks by CPU 0, id hspec 23:36:28.640 -> D: Semaphore is claimed by stopsong 23:36:28.673 -> D: SPI semaphore not taken within 530 ticks by CPU 0, id hspec 23:36:28.673 -> D: Semaphore is claimed by stopsong 23:36:28.673 -> D: SPI semaphore not taken within 540 ticks by CPU 0, id hspec 23:36:28.673 -> D: Semaphore is claimed by stopsong 23:36:28.673 -> D: SPI semaphore not taken within 550 ticks by CPU 0, id hspec 23:36:28.673 -> D: Semaphore is claimed by stopsong 23:36:28.673 -> D: SPI semaphore not taken within 560 ticks by CPU 0, id hspec 23:36:28.673 -> D: Semaphore is claimed by stopsong 23:36:28.706 -> D: SPI semaphore not taken within 570 ticks by CPU 0, id hspec 23:36:28.706 -> D: Semaphore is claimed by stopsong 23:36:28.706 -> D: SPI semaphore not taken within 580 ticks by CPU 1, id hspec 23:36:28.706 -> D: Semaphore is claimed by stopsong 23:36:28.706 -> D: SPI semaphore not taken within 590 ticks by CPU 1, id hspec 23:36:28.706 -> D: Semaphore is claimed by stopsong 23:36:28.739 -> D: SPI semaphore not taken within 600 ticks by CPU 0, id hspec 23:36:28.739 -> D: Semaphore is claimed by stopsong 23:36:28.739 -> D: SPI semaphore not taken within 610 ticks by CPU 0, id hspec 23:36:28.739 -> D: Semaphore is claimed by stopsong 23:36:28.772 -> D: SPI semaphore not taken within 620 ticks by CPU 0, id hspec 23:36:28.772 -> D: Semaphore is claimed by stopsong 23:36:28.772 -> D: Duration mp3loop 400 23:36:28.772 -> D: SPI semaphore not taken within 630 ticks by CPU 0, id hspec 23:36:28.772 -> D: Semaphore is claimed by stopsong 23:36:28.772 -> D: SPI semaphore not taken within 640 ticks by CPU 0, id hspec 23:36:28.772 -> D: Semaphore is claimed by stopsong 23:36:28.772 -> D: SPI semaphore not taken within 650 ticks by CPU 0, id hspec 23:36:28.772 -> D: Semaphore is claimed by stopsong 23:36:28.805 -> D: SPI semaphore not taken within 660 ticks by CPU 0, id hspec 23:36:28.805 -> D: Semaphore is claimed by stopsong 23:36:28.805 -> D: SPI semaphore not taken within 670 ticks by CPU 0, id hspec 23:36:28.805 -> D: Semaphore is claimed by stopsong 23:36:28.805 -> D: SPI semaphore not taken within 680 ticks by CPU 0, id hspec 23:36:28.805 -> D: Semaphore is claimed by stopsong 23:36:28.838 -> D: SPI semaphore not taken within 690 ticks by CPU 1, id hspec 23:36:28.838 -> D: Semaphore is claimed by stopsong 23:36:28.838 -> D: SPI semaphore not taken within 700 ticks by CPU 1, id hspec 23:36:28.838 -> D: Semaphore is claimed by stopsong 23:36:28.838 -> D: SPI semaphore not taken within 710 ticks by CPU 0, id hspec 23:36:28.838 -> D: Semaphore is claimed by stopsong 23:36:28.838 -> D: SPI semaphore not taken within 720 ticks by CPU 0, id hspec 23:36:28.838 -> D: Semaphore is claimed by stopsong 23:36:28.872 -> D: SPI semaphore not taken within 730 ticks by CPU 0, id hspec 23:36:28.872 -> D: Semaphore is claimed by stopsong 23:36:28.872 -> D: SPI semaphore not taken within 740 ticks by CPU 0, id hspec 23:36:28.872 -> D: Semaphore is claimed by stopsong 23:36:28.905 -> D: SPI semaphore not taken within 750 ticks by CPU 0, id hspec 23:36:28.905 -> D: Semaphore is claimed by stopsong 23:36:28.905 -> D: SPI semaphore not taken within 760 ticks by CPU 0, id hspec 23:36:28.905 -> D: Semaphore is claimed by stopsong 23:36:28.905 -> D: SPI semaphore not taken within 770 ticks by CPU 0, id hspec 23:36:28.905 -> D: Semaphore is claimed by stopsong 23:36:28.905 -> D: SPI semaphore not taken within 780 ticks by CPU 0, id hspec 23:36:28.905 -> D: Semaphore is claimed by stopsong 23:36:28.905 -> D: SPI semaphore not taken within 790 ticks by CPU 1, id hspec 23:36:28.905 -> D: Semaphore is claimed by stopsong 23:36:28.938 -> D: SPI semaphore not taken within 800 ticks by CPU 0, id hspec 23:36:28.938 -> D: Semaphore is claimed by stopsong 23:36:28.938 -> D: SPI semaphore not taken within 810 ticks by CPU 0, id hspec 23:36:28.938 -> D: Semaphore is claimed by stopsong 23:36:28.971 -> D: SPI semaphore not taken within 820 ticks by CPU 0, id hspec 23:36:28.971 -> D: Semaphore is claimed by stopsong 23:36:28.971 -> D: SPI semaphore not taken within 830 ticks by CPU 0, id hspec 23:36:28.971 -> D: Semaphore is claimed by stopsong 23:36:28.971 -> D: SPI semaphore not taken within 840 ticks by CPU 0, id hspec 23:36:28.971 -> D: Semaphore is claimed by stopsong 23:36:28.971 -> D: SPI semaphore not taken within 850 ticks by CPU 0, id hspec 23:36:28.971 -> D: Semaphore is claimed by stopsong 23:36:29.004 -> D: SPI semaphore not taken within 860 ticks by CPU 0, id hspec 23:36:29.004 -> D: Semaphore is claimed by stopsong 23:36:29.004 -> D: SPI semaphore not taken within 870 ticks by CPU 0, id hspec 23:36:29.004 -> D: Semaphore is claimed by stopsong 23:36:29.004 -> D: SPI semaphore not taken within 880 ticks by CPU 0, id hspec 23:36:29.004 -> D: Semaphore is claimed by stopsong 23:36:29.037 -> D: SPI semaphore not taken within 890 ticks by CPU 1, id hspec 23:36:29.037 -> D: Semaphore is claimed by stopsong 23:36:29.037 -> D: SPI semaphore not taken within 900 ticks by CPU 1, id hspec 23:36:29.037 -> D: Semaphore is claimed by stopsong 23:36:29.037 -> D: SPI semaphore not taken within 910 ticks by CPU 0, id hspec 23:36:29.037 -> D: Semaphore is claimed by stopsong 23:36:29.037 -> D: SPI semaphore not taken within 920 ticks by CPU 0, id hspec 23:36:29.037 -> D: Semaphore is claimed by stopsong 23:36:29.071 -> D: SPI semaphore not taken within 930 ticks by CPU 0, id hspec 23:36:29.071 -> D: Semaphore is claimed by stopsong 23:36:29.071 -> D: SPI semaphore not taken within 940 ticks by CPU 0, id hspec 23:36:29.071 -> D: Semaphore is claimed by stopsong 23:36:29.104 -> D: SPI semaphore not taken within 950 ticks by CPU 0, id hspec 23:36:29.104 -> D: Semaphore is claimed by stopsong 23:36:29.104 -> D: SPI semaphore not taken within 960 ticks by CPU 0, id hspec 23:36:29.104 -> D: Semaphore is claimed by stopsong 23:36:29.104 -> D: SPI semaphore not taken within 970 ticks by CPU 0, id hspec 23:36:29.104 -> D: Semaphore is claimed by stopsong 23:36:29.104 -> D: SPI semaphore not taken within 980 ticks by CPU 0, id hspec 23:36:29.104 -> D: Semaphore is claimed by stopsong 23:36:29.104 -> D: SPI semaphore not taken within 990 ticks by CPU 0, id hspec 23:36:29.104 -> D: Semaphore is claimed by stopsong 23:36:29.137 -> D: SPI semaphore not taken within 1000 ticks by CPU 1, id hspec 23:36:29.137 -> D: Semaphore is claimed by stopsong 23:36:30.398 -> D: Song stopped incorrectly! 23:36:30.398 -> D: REG Contents 23:36:30.398 -> D: --- ----- 23:36:30.398 -> D: 0 - 808 23:36:30.398 -> D: 1 - 33 23:36:30.398 -> D: 2 - 0 23:36:30.398 -> D: 3 - 0 23:36:30.431 -> D: 4 - 0 23:36:30.431 -> D: 5 - 1F40 23:36:30.431 -> D: 6 - 0 23:36:30.431 -> D: 7 - 0 23:36:30.431 -> D: 8 - 0 23:36:30.431 -> D: 9 - 0 23:36:30.464 -> D: A - 0 23:36:30.464 -> D: B - 4646 23:36:30.464 -> D: C - 0 23:36:30.464 -> D: D - 0 23:36:30.464 -> D: E - 0 23:36:30.464 -> D: F - 0 23:36:30.995 -> D: Metadata block 64 bytes 23:36:30.995 -> D: Streamtitle found, 60 bytes 23:36:30.995 -> D: StreamTitle='NPO Radio 1 - Langs de Lijn En Omstreken - EO';

Edzelf commented 4 years ago

Looks like an SPI error. Is the VCC (5 VOLT) on your VS1053 module strong enough?

minzukas commented 4 years ago

Might be, i do not trust quality of jumper wires. However my esp32 was burned on other project so this one has to wait :)

Mohsin733 commented 4 years ago

D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 770 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 780 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 790 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 800 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 810 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 820 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 830 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 840 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 850 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 860 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 870 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 880 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 890 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 900 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 910 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 920 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 930 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 940 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 950 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 960 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 970 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 980 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 990 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 1000 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: Song stopped incorrectly! D: REG Contents D: --- ----- D: 0 - 808 D: 1 - 33 D: 2 - 0 D: 3 - 6000 D: 4 - 62 D: 5 - AC45 D: 6 - 4051 D: 7 - 1E06 D: 8 - 0 D: 9 - 0 D: A - 0 D: B - F8F8 D: C - 0 D: D - 0 D: E - 0 D: F - FFFF D: Request 7 failed! D: STOP requested D: New preset/file requested (1/0) from airspectrum.cdnstream1.com:8114/1648_128 D: Connect to new host airspectrum.cdnstream1.com:8114/1648_128 D: Connect to airspectrum.cdnstream1.com on port 8114, extension /1648_128 D: SPI semaphore not taken within 120 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 130 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 140 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 150 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 160 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 170 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 180 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 190 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 200 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 210 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 220 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 230 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 240 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 250 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 260 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 270 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 280 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 290 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 300 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 310 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 320 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 330 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 340 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 350 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 360 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 370 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 380 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 390 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: Connected to server D: SPI semaphore not taken within 400 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 410 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 420 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 430 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 440 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 450 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 460 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 470 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 480 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 490 ticks by CPU 1, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 500 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 510 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 520 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 530 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 540 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 550 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 560 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 570 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 580 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 590 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 600 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 610 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 620 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 630 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 640 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 650 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 660 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 670 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 680 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 690 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 700 ticks by CPU 1, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 710 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: SPI semaphore not taken within 720 ticks by CPU 0, id hspectft D: Semaphore is claimed by stopsong D: Switch to HEADER D: Headerline: Content-Type: audio/mpeg D: audio/mpeg seen. D: Headerline: icy-br:128 D: Headerline: e not taken within 730 ticks by CPU 0, id hspectft

I also received the same .....

Edzelf commented 4 years ago

The line: D: 1 - 33 Reveals that you don't have a VS1053 , but an VS1003 chip mounted on your module instead. The ESP32-Radio software will not work for this chip. The new version will test for the right chip and output an error message in the logging is the wrong chip is detected.

Tony763 commented 4 years ago

Hello, I have a same problem:

D: Slow SPI, Testing VS1053 read/write registers...
D: This is not a VS1053, but a VS1003 instead!

D: Semaphore is claimed by stopsong
D: SPI semaphore not taken within 270 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsong
D: Song stopped incorrectly!
D: REG   Contents
D: ---   -----
D: SPI semaphore not taken within 280 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsongD:   0 -   808

D:   1 -    33
D:   2 -  1A7B
D: SPI semaphore not taken within 290 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsong
D:   3 -     0
D:   4 -     0
D: SPI semaphore not taken within 300 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsong
D:   5 -  1F40
D:   6 -  2020
D: SPI semaphore not taken within 310 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsongD:   7 -     0

D:   8 -     0
D:   9 -     0
D: SPI semaphore not taken within 320 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsong
D:   A -     0
D:   B -  F8F8
D: SPI semaphore not taken within 330 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsong
D:   C -     0
D:   D -     0
D: SPI semaphore not taken within 340 ticks by CPU 0, id hspectft
D: Semaphore is claimed by stopsongD:   E -     0

D:   F -     0

Odd is that previous version worked with it and played, there was only sometime lag in sound.

Edzelf commented 4 years ago

Could you please check the printing on the chip? It should say "VLSI Solution VS1053B". For a different chip, the software must be modified. I can not do that, because I only have VS1053 chips.

Tony763 commented 4 years ago

It is really VS1003 as identified, but still it worked. I can help with testing.

macikm commented 4 years ago

"This is not a VS1053, but a VS1003 instead!" same on my setup - but it works (if I comment out line "okay = false ;")

AST-esp-rasp commented 4 years ago

Having the same issue as Minzukas could it be because of the missing display because I use the dummy too?

Edzelf commented 4 years ago

Missing display is allowed. Do you have the right chip VS1053 on your board?

AST-esp-rasp commented 4 years ago

Yes i have and now it works because i probably fixed it by doing the following changes: what id did before: I loaded the initial parameters with the init tool to the NVS and here it says VS1053 DCS is connected to GPIO16 In the main code it says it has to be connected to GPIO32 and i did wire it to GPIO32 remedy: Now after changing config via web interface to GPIO32 it runs esp32_radio main code esp32_radio init code

Derek-K commented 4 years ago

While looking for differences between VS1053 and VS1053B, I come across the datasheet "12.1 Changes Between VS1033c and VS1053a/b Firmware" (page 87) not sure if that has any useful information.

Also on page 88 - "Known quirks, bugs, or features in VS1053b" and there is a 'patch' for VS1053B (last modified: 2020-04-29 V2.8)

About the VS1053 Patch can be found -> here

Just my 2 cents.