mqtt transport error

[georgecarlo]

Hello people,

i'm trying to run the example code of TCP in mqtt. i ve followed the instructions from herehttps://github.com/espressif/esp-idf/tr ... s/mqtt/tcp

but instead of successful connection i get this.

Code: Select all

I (205) MQTT_EXAMPLE: [APP] Startup..
I (205) MQTT_EXAMPLE: [APP] Free memory: 273872 bytes
I (205) MQTT_EXAMPLE: [APP] IDF version: v3.3-beta1-136-g97eecfa-dirty
I (305) wifi: wifi driver task: 3ffc0b0c, prio:23, stack:3584, core=0
I (305) wifi: wifi firmware version: 44ce2e2
I (305) wifi: config NVS flash: enabled
I (305) wifi: config nano formating: disabled
I (305) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (315) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (355) wifi: Init dynamic tx buffer num: 32
I (355) wifi: Init data frame dynamic rx buffer num: 32
I (355) wifi: Init management frame dynamic rx buffer num: 32
I (355) wifi: Init static rx buffer size: 1600
I (355) wifi: Init static rx buffer num: 10
I (365) wifi: Init dynamic rx buffer num: 32
I (365) MQTT_EXAMPLE: start the WIFI SSID:[TPLINK]
I (435) phy: phy_version: 4006, e540b8e, Dec 17 2018, 11:53:06, 0, 0
I (435) wifi: mode : sta (24:0a:c4:9e:fc:28)
I (435) MQTT_EXAMPLE: Waiting for wifi
I (2495) wifi: new:<13,0>, old:<1,0>, ap:<255,255>, sta:<13,0>, prof:1
I (3475) wifi: state: init -> auth (b0)
I (3485) wifi: state: auth -> assoc (0)
I (3485) wifi: state: assoc -> run (10)
I (3505) wifi: connected with TPLINK, channel 13, bssid = 74:da:da:b4:37:c5
I (3505) wifi: pm start, type: 1

I (4295) event: sta ip: 10.229.165.222, mask: 255.255.0.0, gw: 10.229.0.1
I (4295) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
I (4305) MQTT_EXAMPLE: Other event id:7
E (22555) MQTT_CLIENT: Error transport connect
I (22555) MQTT_EXAMPLE: MQTT_EVENT_DISCONNECTED

[ESP_cermak]

Hi George,

The error `Error transport connect` means that a connection to a broker cannot be established and since it's a tcp connection it could be caused by:
- not resolving the dns name
- socket connection error

Which broker are you connecting to, is it the default iot.eclipse.org:1883? Is this site accessible from your network?

You can enable more verbose logging in menuconfig (components -> LogLevel -> Debug), you may also like to comment out this line in the example main to enable debug level also in other components (such as tcp_transport where the error resides)

Code: Select all

    // esp_log_level_set("*", ESP_LOG_INFO);

The 'happy-flow' looks something like:

Code: Select all

I (4743) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (4753) MQTT_CLIENT: MQTT client_id=ESP32_09885C
D (4763) MQTT_CLIENT: Core selection disabled
I (4763) MQTT_EXAMPLE: Other event id:7
D (4783) TRANS_TCP: [sock=54],connecting to server IP:198.41.30.241,Port:1883...
D (4853) phy_init: wifi mac time delta: 20099
D (4963) MQTT_CLIENT: Transport connected to mqtt://iot.eclipse.org:1883
I (4963) MQTT_CLIENT: Sending MQTT CONNECT message, type: 1, id: 0000

[georgecarlo]

Hey ESP, thanks for your reply i changed the menuconfig, to debug, also uncommented that log line and yes im connecting to default iot.eclipse.org:1883

but this is what i get, scroll a bit deep , help me with this issue. Thanks

Code: Select all

D (556) event: SYSTEM_EVENT_STA_START
I (556) MQTT_EXAMPLE: Waiting for wifi
I (2616) wifi: new:<13,0>, old:<1,0>, ap:<255,255>, sta:<13,0>, prof:1
I (3596) wifi: state: init -> auth (b0)
I (3606) wifi: state: auth -> assoc (0)
I (3616) wifi: state: assoc -> run (10)
I (3626) wifi: connected with TPLINK, channel 13, bssid = 74:da:da:b4:37:c5
I (3626) wifi: pm start, type: 1

D (3626) event: SYSTEM_EVENT_STA_CONNECTED, ssid:TPLINK, ssid_len:6, bssid:74:da:da:b4:37:c5, channel:13, authmode:3
D (3636) tcpip_adapter: dhcp client init ip/mask/gw to all-0
D (3646) tcpip_adapter: if0 start ip lost tmr: enter
D (3646) tcpip_adapter: if0 start ip lost tmr: no need start because netif=0x3ffc59f0 interval=120 ip=0
D (3656) tcpip_adapter: dhcp client start successfully
D (3746) phy_init: wifi mac time delta: 11211
D (3846) phy_init: wifi mac time delta: 13500
D (3956) phy_init: wifi mac time delta: 94696
D (4056) phy_init: wifi mac time delta: 95912
D (4156) phy_init: wifi mac time delta: 37795
D (4256) phy_init: wifi mac time delta: 96402
D (4276) phy_init: wifi mac time delta: 5242
D (4286) tcpip_adapter: if0 dhcpc cb
D (4286) tcpip_adapter: if0 ip changed=1
D (4286) event: SYSTEM_EVENT_STA_GOT_IP, ip:10.229.65.239, mask:255.255.0.0, gw:10.229.0.1
I (4286) event: sta ip: 10.229.65.239, mask: 255.255.0.0, gw: 10.229.0.1
uri: mqtt://192.168.1.15I (4296) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (4306) MQTT_CLIENT: MQTT client_id=ESP32_9eFC28
D (4316) MQTT_CLIENT: Core selection disabled
D (4316) MQTT_CLIENT: test: Transport connected to mqtt://iot.eclipse.org:1883
c function called!I (4326) MQTT_EXAMPLE: Other event id:7
D (4466) phy_init: wifi mac time delta: 67996
D (4566) phy_init: wifi mac time delta: 40473
D (4666) phy_init: wifi mac time delta: 96387
D (4776) phy_init: wifi mac time delta: 96327
D (4876) phy_init: wifi mac time delta: 40558
D (4976) phy_init: wifi mac time delta: 95165
D (5076) phy_init: wifi mac time delta: 96394
D (5186) phy_init: wifi mac time delta: 96380
D (5286) phy_init: wifi mac time delta: 93795
D (5296) TRANS_TCP: [sock=54],connecting to server IP:198.41.30.241,Port:1883...
D (5386) phy_init: wifi mac time delta: 24844
D (5596) phy_init: wifi mac time delta: 96442
D (5696) phy_init: wifi mac time delta: 96399
D (5796) phy_init: wifi mac time delta: 96359
D (5896) phy_init: wifi mac time delta: 96381
D (6006) phy_init: wifi mac time delta: 94651
D (6106) phy_init: wifi mac time delta: 95297
D (6206) phy_init: wifi mac time delta: 96403
D (6286) phy_init: wifi mac time delta: 69778
D (6406) phy_init: wifi mac time delta: 67861
D (6516) phy_init: wifi mac time delta: 96377
D (6616) phy_init: wifi mac time delta: 94958
D (6816) phy_init: wifi mac time delta: 73823
D (7026) phy_init: wifi mac time delta: 96398
D (7126) phy_init: wifi mac time delta: 96320
D (7226) phy_init: wifi mac time delta: 90219
D (7286) phy_init: wifi mac time delta: 45852
D (7436) phy_init: wifi mac time delta: 90106
D (7536) phy_init: wifi mac time delta: 96405
D (7636) phy_init: wifi mac time delta: 96307
D (7746) phy_init: wifi mac time delta: 96375
D (7846) phy_init: wifi mac time delta: 96305
D (7946) phy_init: wifi mac time delta: 95284
D (8046) phy_init: wifi mac time delta: 96425
D (8156) phy_init: wifi mac time delta: 42324
D (8256) phy_init: wifi mac time delta: 96413
D (8356) phy_init: wifi mac time delta: 96379
D (8456) phy_init: wifi mac time delta: 96390
D (8566) phy_init: wifi mac time delta: 95348
D (8666) phy_init: wifi mac time delta: 96413
D (8766) phy_init: wifi mac time delta: 96390
D (8866) phy_init: wifi mac time delta: 96406
D (8966) phy_init: wifi mac time delta: 95337
D (9076) phy_init: wifi mac time delta: 96446
D (9176) phy_init: wifi mac time delta: 96359
D (9276) phy_init: wifi mac time delta: 96369
D (9376) phy_init: wifi mac time delta: 96376
D (9486) phy_init: wifi mac time delta: 96386
D (9586) phy_init: wifi mac time delta: 91705
D (9686) phy_init: wifi mac time delta: 96385
D (9786) phy_init: wifi mac time delta: 95615
D (9896) phy_init: wifi mac time delta: 96363
D (9996) phy_init: wifi mac time delta: 95201
D (10096) phy_init: wifi mac time delta: 96438
D (10196) phy_init: wifi mac time delta: 96348
D (10306) phy_init: wifi mac time delta: 96350
D (10406) phy_init: wifi mac time delta: 96385
D (10506) phy_init: wifi mac time delta: 96387
D (10606) phy_init: wifi mac time delta: 95352
D (10716) phy_init: wifi mac time delta: 96405
D (10816) phy_init: wifi mac time delta: 96373
D (11016) phy_init: wifi mac time delta: 96468
D (11056) phy_init: wifi mac time delta: 27276
D (11126) phy_init: wifi mac time delta: 11916
D (11226) phy_init: wifi mac time delta: 96412
D (11326) phy_init: wifi mac time delta: 96392
D (11426) phy_init: wifi mac time delta: 96399
D (11526) phy_init: wifi mac time delta: 96383
D (11636) phy_init: wifi mac time delta: 96392
D (11736) phy_init: wifi mac time delta: 96371
D (11836) phy_init: wifi mac time delta: 96399
D (11936) phy_init: wifi mac time delta: 94711
D (12046) phy_init: wifi mac time delta: 96417
D (12146) phy_init: wifi mac time delta: 96276
D (12246) phy_init: wifi mac time delta: 96358
D (12346) phy_init: wifi mac time delta: 96388
D (12456) phy_init: wifi mac time delta: 96010
D (12556) phy_init: wifi mac time delta: 95318
D (12656) phy_init: wifi mac time delta: 96420
D (12756) phy_init: wifi mac time delta: 96338
D (12866) phy_init: wifi mac time delta: 96402
D (12966) phy_init: wifi mac time delta: 95209
D (13066) phy_init: wifi mac time delta: 96444
D (13166) phy_init: wifi mac time delta: 96354
D (13276) phy_init: wifi mac time delta: 96372
D (13376) phy_init: wifi mac time delta: 96387
D (13476) phy_init: wifi mac time delta: 96383
D (13576) phy_init: wifi mac time delta: 96394
D (13686) phy_init: wifi mac time delta: 96377
D (13786) phy_init: wifi mac time delta: 96200
D (13886) phy_init: wifi mac time delta: 96375
D (13986) phy_init: wifi mac time delta: 95283
D (14056) phy_init: wifi mac time delta: 57738
D (14196) phy_init: wifi mac time delta: 80316
D (14296) phy_init: wifi mac time delta: 96414
D (14396) phy_init: wifi mac time delta: 95779
D (14496) phy_init: wifi mac time delta: 96313
D (14606) phy_init: wifi mac time delta: 95312
D (14706) phy_init: wifi mac time delta: 96407
D (14806) phy_init: wifi mac time delta: 96362
D (14906) phy_init: wifi mac time delta: 96377
D (15016) phy_init: wifi mac time delta: 95308
D (15116) phy_init: wifi mac time delta: 96410
D (15216) phy_init: wifi mac time delta: 96363
D (15316) phy_init: wifi mac time delta: 96381
D (15426) phy_init: wifi mac time delta: 96352
D (15526) phy_init: wifi mac time delta: 96395
D (15626) phy_init: wifi mac time delta: 95635
D (15726) phy_init: wifi mac time delta: 96388
D (15836) phy_init: wifi mac time delta: 96366
D (15936) phy_init: wifi mac time delta: 95272
D (16036) phy_init: wifi mac time delta: 96378
D (16136) phy_init: wifi mac time delta: 95134
D (16246) phy_init: wifi mac time delta: 96398
D (16346) phy_init: wifi mac time delta: 95997
D (16446) phy_init: wifi mac time delta: 95733
D (16546) phy_init: wifi mac time delta: 94792
D (16646) phy_init: wifi mac time delta: 96395
D (16756) phy_init: wifi mac time delta: 96377
D (16856) phy_init: wifi mac time delta: 96222
D (16956) phy_init: wifi mac time delta: 95316
D (17056) phy_init: wifi mac time delta: 88138
D (17166) phy_init: wifi mac time delta: 49934
D (17266) phy_init: wifi mac time delta: 96192
D (17366) phy_init: wifi mac time delta: 96265
D (17466) phy_init: wifi mac time delta: 95775
D (17576) phy_init: wifi mac time delta: 96370
D (17676) phy_init: wifi mac time delta: 96317
D (17776) phy_init: wifi mac time delta: 96284
D (17876) phy_init: wifi mac time delta: 96310
D (17986) phy_init: wifi mac time delta: 95200
D (18086) phy_init: wifi mac time delta: 96423
D (18186) phy_init: wifi mac time delta: 96232
D (18286) phy_init: wifi mac time delta: 96140
D (18396) phy_init: wifi mac time delta: 96304
D (18496) phy_init: wifi mac time delta: 96388
D (18596) phy_init: wifi mac time delta: 95303
D (18696) phy_init: wifi mac time delta: 96394
D (18806) phy_init: wifi mac time delta: 96373
D (18906) phy_init: wifi mac time delta: 96387
D (19006) phy_init: wifi mac time delta: 95327
D (19106) phy_init: wifi mac time delta: 96413
D (19206) phy_init: wifi mac time delta: 96330
D (19316) phy_init: wifi mac time delta: 96375
D (19416) phy_init: wifi mac time delta: 95007
D (19516) phy_init: wifi mac time delta: 96364
D (19616) phy_init: wifi mac time delta: 96382
D (19726) phy_init: wifi mac time delta: 95832
D (19826) phy_init: wifi mac time delta: 96383
D (19926) phy_init: wifi mac time delta: 95313
D (20026) phy_init: wifi mac time delta: 96396
D (20056) phy_init: wifi mac time delta: 16189
D (20136) phy_init: wifi mac time delta: 23266
D (20236) phy_init: wifi mac time delta: 96374
D (20336) phy_init: wifi mac time delta: 96330
D (20436) phy_init: wifi mac time delta: 96383
D (20546) phy_init: wifi mac time delta: 95314
D (20646) phy_init: wifi mac time delta: 96436
D (20746) phy_init: wifi mac time delta: 96057
D (20846) phy_init: wifi mac time delta: 96405
D (20956) phy_init: wifi mac time delta: 95343
D (21056) phy_init: wifi mac time delta: 96417
D (21156) phy_init: wifi mac time delta: 96362
D (21256) phy_init: wifi mac time delta: 96168
D (21366) phy_init: wifi mac time delta: 95361
D (21466) phy_init: wifi mac time delta: 96386
D (21566) phy_init: wifi mac time delta: 96402
D (21666) phy_init: wifi mac time delta: 96397
D (21766) phy_init: wifi mac time delta: 96406
D (21876) phy_init: wifi mac time delta: 96374
D (21976) phy_init: wifi mac time delta: 95344
D (22076) phy_init: wifi mac time delta: 96427
D (22176) phy_init: wifi mac time delta: 96346
D (22286) phy_init: wifi mac time delta: 96401
D (22386) phy_init: wifi mac time delta: 96388
D (22486) phy_init: wifi mac time delta: 96255
D (22586) phy_init: wifi mac time delta: 95291
D (22696) phy_init: wifi mac time delta: 96403
D (22796) phy_init: wifi mac time delta: 96350
D (22896) phy_init: wifi mac time delta: 96398
D (22996) phy_init: wifi mac time delta: 95298
D (23056) phy_init: wifi mac time delta: 46594
D (23206) phy_init: wifi mac time delta: 90447
D (23306) phy_init: wifi mac time delta: 96409
D (23406) phy_init: wifi mac time delta: 96352
D (23516) phy_init: wifi mac time delta: 96326
E (23556) MQTT_CLIENT: Error transport connect
D (23556) MQTT_CLIENT: Reconnect after 10000 ms
I (23556) MQTT_EXAMPLE: MQTT_EVENT_DISCONNECTED

and again i checked it with my localhost, and i get the following log

Code: Select all

I (446) MQTT_EXAMPLE: start the WIFI SSID:[TPLINK]
D (456) RTC_MODULE: Wi-Fi takes adc2 lock.
D (456) phy_init: loading PHY init data from application binary
D (466) nvs: nvs_open_from_partition phy 0
D (466) nvs: nvs_get cal_version 4
D (476) nvs: nvs_get_str_or_blob cal_mac
D (476) nvs: nvs_get_str_or_blob cal_data
D (486) nvs: nvs_close 3
I (536) phy: phy_version: 4006, e540b8e, Dec 17 2018, 11:53:06, 0, 0
I (546) wifi: mode : sta (24:0a:c4:9e:fc:28)
D (546) event: SYSTEM_EVENT_STA_START
I (546) MQTT_EXAMPLE: Waiting for wifi
I (2596) wifi: new:<13,0>, old:<1,0>, ap:<255,255>, sta:<13,0>, prof:1
I (3576) wifi: state: init -> auth (b0)
I (3586) wifi: state: auth -> assoc (0)
I (3596) wifi: state: assoc -> run (10)
I (3616) wifi: connected with TPLINK, channel 13, bssid = 74:da:da:b4:37:c5
I (3616) wifi: pm start, type: 1

D (3616) event: SYSTEM_EVENT_STA_CONNECTED, ssid:TPLINK, ssid_len:6, bssid:74:da:da:b4:37:c5, channel:13, authmode:3
D (3626) tcpip_adapter: dhcp client init ip/mask/gw to all-0
D (3636) tcpip_adapter: if0 start ip lost tmr: enter
D (3636) tcpip_adapter: if0 start ip lost tmr: no need start because netif=0x3ffc59f0 interval=120 ip=0
D (3646) tcpip_adapter: dhcp client start successfully
D (3776) phy_init: wifi mac time delta: 20082
D (3876) phy_init: wifi mac time delta: 46120
D (4086) phy_init: wifi mac time delta: 96281
D (4186) phy_init: wifi mac time delta: 96367
D (4266) phy_init: wifi mac time delta: 68699
D (4276) tcpip_adapter: if0 dhcpc cb
D (4276) tcpip_adapter: if0 ip changed=1
D (4276) event: SYSTEM_EVENT_STA_GOT_IP, ip:10.229.71.30, mask:255.255.0.0, gw:10.229.0.1
I (4276) event: sta ip: 10.229.71.30, mask: 255.255.0.0, gw: 10.229.0.1
uri: mqtt://192.168.1.15I (4286) system_api: Base MAC address is not set, read default base MAC address from BLK0 of EFUSE
D (4296) MQTT_CLIENT: MQTT client_id=ESP32_9eFC28
D (4306) MQTT_CLIENT: Core selection disabled
D (4306) MQTT_CLIENT: test: Transport connected to mqtt://192.168.1.15:80
c function called!I (4316) MQTT_EXAMPLE: Other event id:7
D (4316) TRANS_TCP: [sock=54],connecting to server IP:192.168.1.15,Port:80...
D (4496) phy_init: wifi mac time delta: 41540
D (4596) phy_init: wifi mac time delta: 94391
D (4696) phy_init: wifi mac time delta: 94832
D (4806) phy_init: wifi mac time delta: 96414
D (4906) phy_init: wifi mac time delta: 95253
D (5006) phy_init: wifi mac time delta: 96391
D (5106) phy_init: wifi mac time delta: 96374
D (5216) phy_init: wifi mac time delta: 95248
D (5276) phy_init: wifi mac time delta: 54822
D (5416) phy_init: wifi mac time delta: 42516
D (5516) phy_init: wifi mac time delta: 96345
D (5616) phy_init: wifi mac time delta: 96321
D (5726) phy_init: wifi mac time delta: 96359
D (5826) phy_init: wifi mac time delta: 96294
D (5926) phy_init: wifi mac time delta: 96374
D (6026) phy_init: wifi mac time delta: 96342
D (6136) phy_init: wifi mac time delta: 96400
D (6236) phy_init: wifi mac time delta: 96339
D (6276) phy_init: wifi mac time delta: 28371
D (6436) phy_init: wifi mac time delta: 44233
D (6546) phy_init: wifi mac time delta: 96397
D (6646) phy_init: wifi mac time delta: 96374
D (6746) phy_init: wifi mac time delta: 96368
D (6846) phy_init: wifi mac time delta: 96359
D (6956) phy_init: wifi mac time delta: 96389
D (7056) phy_init: wifi mac time delta: 96363
D (7076) phy_init: wifi mac time delta: 11972
D (7076) MQTT_CLIENT: Transport connected to mqtt://192.168.1.15:80
I (7086) MQTT_CLIENT: Sending MQTT CONNECT message, type: 1, id: 0000
E (7106) MQTT_CLIENT: Invalid MSG_TYPE response: 4, read_len: 328
I (7106) MQTT_CLIENT: Error MQTT Connected
D (7106) MQTT_CLIENT: Reconnect after 10000 ms
I (7106) MQTT_EXAMPLE: MQTT_EVENT_DISCONNECTED

[ESP_cermak]

ok, so there no issue with dns resolution and iot.eclipse.org is accessible from your network, so the only issue I can think of might be a blocked port 1883. can you please check there's no firewall involved and/or this port is enabled in your network? another option is to check out the ws example which connects also to iot.eclipse.org but with ws over port 80.

by connecting to localhost, you mean you have installed mosquitto (or some other broker) on 192.168.1.15 listening for tcp mqtt messages on port 80?
From the log it looks like the transport connects (unlike the iot.eclipse.org), client sends a connect message but receives some unexpected data. It doesn't seem the server is setup correctly.

[georgecarlo]

hey esp, thanks. I got a seperate esp-mqtt from here https://github.com/espressif/esp-mqtt and it worked fine. Thank you.

[ESP_cermak]

Are you saying that when using a different commit from https://github.com/espressif/esp-mqtt than the one specified in idf, then mqtt connection worked and with the default one did not?

Can you please share the exact commit id's of esp-idf and esp-mqtt which you used for the version with worked and the one which did not?
(`git rev-parse HEAD` inside the repo).

Thanks for your help!
David

[georgecarlo]

Are you saying that when using a different commit from https://github.com/espressif/esp-mqtt than the one specified in idf, then mqtt connection worked and with the default one did not?

Can you please share the exact commit id's of esp-idf and esp-mqtt which you used for the version with worked and the one which did not?
(`git rev-parse HEAD` inside the repo).

Thanks for your help!
David

Hey noted that i used your github code which worked (the master one which i downloaded on jan30) , the one which did'nt work is the one here https://github.com/espressif/esp-idf/tr ... ocols/mqtt tcp from this link. and guess i download esp-idf 15 days ago. dont rem exact date and version is v3.3-beta1-136-g97eecfa